-- Logs begin at Sun 2024-04-14 20:03:25 +07, end at Sun 2024-04-14 20:05:51 +07. -- Apr 14 20:04:02 volumio sudo[977]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 14 20:04:02 volumio sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:02 volumio sudo[977]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:02 volumio sudo[979]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 14 20:04:02 volumio sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:02 volumio sudo[979]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:02 volumio wireless.js[552]: Hotspot is disabled, not starting it Apr 14 20:04:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 14 20:04:02 volumio systemd[1]: wireless.service: Succeeded. Apr 14 20:04:02 volumio systemd[1]: Started Wireless Services. Apr 14 20:04:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 14 20:04:02 volumio systemd[1]: Started Volumio Backend Module. Apr 14 20:04:02 volumio systemd[1]: Started Volumio Cpu Tweaker. Apr 14 20:04:02 volumio systemd[1]: Reached target Multi-User System. Apr 14 20:04:02 volumio systemd[1]: Reached target Graphical Interface. Apr 14 20:04:02 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 14 20:04:02 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 14 20:04:02 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 14 20:04:02 volumio systemd[1]: Startup finished in 9.168s (kernel) + 39.099s (userspace) = 48.268s. Apr 14 20:04:02 volumio volumio-cpu-tweak[986]: Setting RT Priority for mpd Apr 14 20:04:02 volumio volumio-cpu-tweak[986]: pid 35's current scheduling policy: SCHED_OTHER Apr 14 20:04:02 volumio volumio-cpu-tweak[986]: pid 35's current scheduling priority: 0 Apr 14 20:04:02 volumio volumio-cpu-tweak[986]: Setting MPD Affinity Apr 14 20:04:02 volumio volumio-cpu-tweak[986]: pid 3's current affinity mask: f Apr 14 20:04:02 volumio volumio-cpu-tweak[986]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 14 20:04:02 volumio volumio-cpu-tweak[986]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 14 20:04:02 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 14 20:04:03 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 14 20:04:04 volumio volumio[985]: info: ------------------------------------------- Apr 14 20:04:04 volumio volumio[985]: info: ----- Volumio3 ---- Apr 14 20:04:04 volumio volumio[985]: info: ------------------------------------------- Apr 14 20:04:04 volumio volumio[985]: info: ----- System startup ---- Apr 14 20:04:04 volumio volumio[985]: info: ------------------------------------------- Apr 14 20:04:04 volumio volumio-remote-updater[541]: [2024-04-14 20:04:04] [connect] Successful connection Apr 14 20:04:04 volumio volumio[985]: info: MYVOLUMIO Environment detected Apr 14 20:04:04 volumio volumio[985]: info: Plugin folders cleanup Apr 14 20:04:04 volumio volumio[985]: info: Scanning into folder /volumio/app/plugins/ Apr 14 20:04:04 volumio volumio[985]: info: Scanning category audio_interface Apr 14 20:04:04 volumio volumio[985]: info: Scanning category miscellanea Apr 14 20:04:04 volumio volumio[985]: info: Scanning category music_service Apr 14 20:04:04 volumio volumio[985]: info: Scanning category plugins.json Apr 14 20:04:04 volumio volumio[985]: info: Scanning category system_controller Apr 14 20:04:04 volumio volumio[985]: info: Scanning category user_interface Apr 14 20:04:04 volumio volumio[985]: info: Scanning into folder /data/plugins/ Apr 14 20:04:04 volumio volumio[985]: info: Scanning category music_service Apr 14 20:04:04 volumio volumio[985]: info: Plugin folders cleanup completed Apr 14 20:04:04 volumio volumio[985]: info: ------------------------------------------- Apr 14 20:04:04 volumio volumio[985]: info: ----- Core plugins startup ---- Apr 14 20:04:04 volumio volumio[985]: info: ------------------------------------------- Apr 14 20:04:04 volumio volumio[985]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 20:04:04 volumio volumio[985]: info: Adding plugin upnp to MyMusic Plugins Apr 14 20:04:04 volumio volumio[985]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 20:04:04 volumio volumio[985]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 20:04:04 volumio volumio[985]: info: Loading plugins from folder /data/plugins/ Apr 14 20:04:04 volumio volumio[985]: info: Loading plugin "system"... Apr 14 20:04:04 volumio volumio[985]: info: Loading plugin "appearance"... Apr 14 20:04:05 volumio volumio[985]: info: Loading plugin "network"... Apr 14 20:04:05 volumio volumio[985]: info: Refreshing Cached IP Addresses Apr 14 20:04:05 volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 20:04:05 volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:05 volumio volumio[985]: info: Loading plugin "services"... Apr 14 20:04:05 volumio sudo[1019]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:05 volumio sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 20:04:05 volumio sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:05 volumio volumio[985]: info: Loading plugin "alsa_controller"... Apr 14 20:04:05 volumio sudo[1021]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:05 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 20:04:05 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:05 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 20:04:05 volumio volumio[985]: info: Loading plugin "wizard"... Apr 14 20:04:05 volumio volumio[985]: info: Loading plugin "networkfs"... Apr 14 20:04:05 volumio volumio[985]: info: Starting Udev Watcher for removable devices Apr 14 20:04:06 volumio volumio[985]: info: Ignoring mount for partition: boot Apr 14 20:04:06 volumio volumio[985]: info: Ignoring mount for partition: volumio Apr 14 20:04:06 volumio volumio[985]: info: Ignoring mount for partition: volumio_data Apr 14 20:04:06 volumio volumio[985]: info: Mounting Device DKPhuong_USB Apr 14 20:04:06 volumio sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/DKPhuong_USB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 14 20:04:06 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:08 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:19 volumio volumio-remote-updater[541]: [2024-04-14 20:04:19] [connect] Successful connection Apr 14 20:04:34 volumio volumio-remote-updater[541]: [2024-04-14 20:04:34] [connect] Successful connection Apr 14 20:04:36 volumio kernel: sd 0:0:0:0: [sda] tag#27 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN Apr 14 20:04:36 volumio kernel: sd 0:0:0:0: [sda] tag#27 CDB: opcode=0x28 28 00 00 00 09 08 00 00 f8 00 Apr 14 20:04:36 volumio kernel: scsi host0: uas_eh_device_reset_handler start Apr 14 20:04:36 volumio kernel: usb 2-1: reset SuperSpeed USB device number 2 using xhci_hcd Apr 14 20:04:36 volumio kernel: scsi host0: uas_eh_device_reset_handler success Apr 14 20:04:37 volumio kernel: fuse: init (API version 7.37) Apr 14 20:04:37 volumio systemd[1]: Mounting FUSE Control File System... Apr 14 20:04:37 volumio systemd[1]: Mounted FUSE Control File System. Apr 14 20:04:37 volumio ntfs-3g[1072]: Version 2017.3.23AR.3 integrated FUSE 28 Apr 14 20:04:37 volumio ntfs-3g[1072]: Mounted /dev/sda1 (Read-Write, label "DKPhuong_USB", NTFS 3.1) Apr 14 20:04:37 volumio ntfs-3g[1072]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 14 20:04:37 volumio ntfs-3g[1072]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Apr 14 20:04:37 volumio ntfs-3g[1072]: Global ownership and permissions enforced, configuration type 7 Apr 14 20:04:37 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 20:04:37 volumio volumio[985]: info: Loading plugin "volumio_command_line_client"... Apr 14 20:04:37 volumio volumio[985]: info: Loading plugin "upnp"... Apr 14 20:04:37 volumio volumio[985]: info: [1713099877519] Starting Upmpd Daemon Apr 14 20:04:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 20:04:37 volumio volumio[985]: info: Loading plugin "my_music"... Apr 14 20:04:37 volumio volumio[985]: info: Loading plugin "mpd"... Apr 14 20:04:37 volumio volumio[985]: info: Loading plugin "upnp_browser"... Apr 14 20:04:38 volumio volumio[985]: info: Loading plugin "alarm-clock"... Apr 14 20:04:38 volumio ntpd[698]: Soliciting pool server 203.113.174.44 Apr 14 20:04:38 volumio volumio[985]: info: Loading plugin "airplay_emulation"... Apr 14 20:04:38 volumio volumio[985]: info: Starting Shairport Sync Apr 14 20:04:38 volumio volumio[985]: info: Loading plugin "last_100"... Apr 14 20:04:38 volumio volumio[985]: info: Loading plugin "webradio"... Apr 14 20:04:38 volumio volumio[985]: info: Loading plugin "i2s_dacs"... Apr 14 20:04:38 volumio volumio[985]: info: I2S DAC not set, start Auto-detection Apr 14 20:04:38 volumio volumio[985]: info: Loading plugin "volumiodiscovery"... Apr 14 20:04:38 volumio volumio[985]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 20:04:38 volumio volumio[985]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 20:04:38 volumio node[985]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 20:04:38 volumio volumio[985]: *** WARNING *** For more information see Apr 14 20:04:38 volumio volumio[985]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 20:04:38 volumio volumio[985]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 20:04:38 volumio volumio[985]: *** WARNING *** For more information see Apr 14 20:04:38 volumio node[985]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 20:04:38 volumio node[985]: *** WARNING *** For more information see Apr 14 20:04:38 volumio node[985]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 20:04:38 volumio node[985]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 20:04:38 volumio node[985]: *** WARNING *** For more information see Apr 14 20:04:38 volumio volumio[985]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 20:04:38 volumio volumio[985]: info: Discovery: Started advertising with name: Volumio Apr 14 20:04:38 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 20:04:38 volumio volumio[985]: info: Loading plugin "spop"... Apr 14 20:04:38 volumio ntpd[698]: Soliciting pool server 103.130.217.41 Apr 14 20:04:39 volumio volumio[985]: info: Loading plugin "outputs"... Apr 14 20:04:39 volumio volumio[985]: info: Loading plugin "albumart"... Apr 14 20:04:39 volumio volumio[985]: info: Plugin example_plugin is not enabled Apr 14 20:04:39 volumio volumio[985]: info: Loading plugin "inputs"... Apr 14 20:04:39 volumio volumio[985]: info: Loading plugin "updater_comm"... Apr 14 20:04:39 volumio volumio[985]: info: Plugin mpdemulation is not enabled Apr 14 20:04:39 volumio volumio[985]: info: Loading plugin "rest_api"... Apr 14 20:04:39 volumio volumio[985]: info: Loading plugin "websocket"... Apr 14 20:04:39 volumio volumio[985]: info: Loading i18n strings for locale en Apr 14 20:04:39 volumio volumio[985]: Updating browse sources language Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 20:04:39 volumio volumio[985]: Forking 3 albumart workers Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::initPlayerControls Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:04:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 20:04:39 volumio volumio[985]: Express server listening on port 3000 Apr 14 20:04:39 volumio volumio[985]: [Metrics] WebUI: 36s 505.96ms Apr 14 20:04:40 volumio volumio[985]: info: CoreStateMachine::resetVolumioState Apr 14 20:04:40 volumio volumio[985]: info: CoreStateMachine::getcurrentVolume Apr 14 20:04:40 volumio volumio[985]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 20:04:40 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:04:40 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:40 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:04:40 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:04:40 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 20:04:40 volumio volumio[985]: info: Reloading queue from file Apr 14 20:04:40 volumio volumio[985]: info: CoreStateMachine::setRepeat null single undefined Apr 14 20:04:40 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:04:40 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:40 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:04:40 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:04:40 volumio volumio[985]: info: CoreStateMachine::setRandom null Apr 14 20:04:40 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:04:40 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:40 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:04:40 volumio volumio[985]: info: Setting Device type: Raspberry PI Apr 14 20:04:40 volumio volumio[985]: info: Discovery: adding 63a43469-2eb9-424c-8ae5-827f3ecabf2a Apr 14 20:04:40 volumio volumio[985]: info: Discovery: Found device Volumio Apr 14 20:04:40 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:04:40 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:40 volumio volumio[985]: Starting albumart workers Apr 14 20:04:40 volumio volumio[985]: Starting albumart workers Apr 14 20:04:40 volumio volumio[985]: Starting albumart workers Apr 14 20:04:40 volumio ntpd[698]: Soliciting pool server 2406:da14:10c4:9aa0:123:: Apr 14 20:04:41 volumio sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 20:04:41 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:41 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:41 volumio sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 20:04:41 volumio sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:41 volumio sudo[1138]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:41 volumio volumio[985]: verbose: New Socket.io Connection to 192.168.1.111 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Mobile Safari/537.36 Total Clients: 1 Apr 14 20:04:42 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:04:43 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 20:04:43 volumio volumio[985]: info: Received Get System Info Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:04:43 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:04:43 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:04:43 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:43 volumio volumio[985]: info: Listing playlists Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 20:04:43 volumio volumio[985]: info: Received Get System Info Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:04:43 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:04:43 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:04:46 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 20:04:46 volumio volumio[985]: info: Received Get System Info Apr 14 20:04:46 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:04:46 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:04:46 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 20:04:46 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:04:46 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:04:46 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:04:47 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 14 20:04:47 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 20:04:47 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:47 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:47 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 20:04:47 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:47 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:47 volumio sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 20:04:47 volumio sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:04:47 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 14 20:04:47 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root Apr 14 20:04:47 volumio volumio[985]: info: Upmpdcli Daemon Started Apr 14 20:04:47 volumio volumio[985]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 14 20:05:12 volumio volumio-remote-updater[541]: [2024-04-14 20:05:12] [connect] Successful connection Apr 14 20:05:12 volumio volumio-remote-updater[541]: [2024-04-14 20:05:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713099912 101 Apr 14 20:05:12 volumio volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Apr 14 20:05:14 volumio volumio[985]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 14 20:05:18 volumio volumio[985]: info: CoreCommandRouter::volumioGetQueue Apr 14 20:05:18 volumio volumio[985]: info: CoreStateMachine::getQueue Apr 14 20:05:18 volumio volumio[985]: info: CorePlayQueue::getQueue Apr 14 20:05:20 volumio volumio[985]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 14 20:05:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 20:05:22 volumio volumio[985]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 20:05:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 20:05:22 volumio volumio[985]: info: Received Get System Version Apr 14 20:05:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 20:05:22 volumio volumio[985]: info: Received Get System Info Apr 14 20:05:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:05:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:05:22 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 20:05:22 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:05:22 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:05:24 volumio volumio[985]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::ClearQueue Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::stop Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:24 volumio volumio[985]: info: CorePlayQueue::clearPlayQueue Apr 14 20:05:24 volumio volumio[985]: info: CorePlayQueue::saveQueue Apr 14 20:05:24 volumio volumio[985]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::addQueueItems Apr 14 20:05:24 volumio volumio[985]: info: CorePlayQueue::addQueueItems Apr 14 20:05:24 volumio volumio[985]: info: Preload queue cleared Apr 14 20:05:24 volumio volumio[985]: info: Adding Item to queue: music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993) Apr 14 20:05:24 volumio volumio[985]: info: Exploding uri music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993) in service mpd Apr 14 20:05:24 volumio volumio[985]: error: Failed to stat '/mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)' Apr 14 20:05:24 volumio volumio[985]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:05:24 volumio volumio[985]: info: CorePlayQueue::saveQueue Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::updateTrackBlock Apr 14 20:05:24 volumio volumio[985]: info: CorePlayQueue::getTrackBlock Apr 14 20:05:24 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::play index 0 Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::stop Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 14 20:05:24 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:24 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:28 volumio volumio[985]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::ClearQueue Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::stop Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:28 volumio volumio[985]: info: CorePlayQueue::clearPlayQueue Apr 14 20:05:28 volumio volumio[985]: info: CorePlayQueue::saveQueue Apr 14 20:05:28 volumio volumio[985]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::addQueueItems Apr 14 20:05:28 volumio volumio[985]: info: CorePlayQueue::addQueueItems Apr 14 20:05:28 volumio volumio[985]: info: Preload queue cleared Apr 14 20:05:28 volumio volumio[985]: info: Adding Item to queue: music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Tango Around World Apr 14 20:05:28 volumio volumio[985]: info: Exploding uri music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Tango Around World in service mpd Apr 14 20:05:28 volumio volumio[985]: error: Failed to stat '/mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Tango Around World' Apr 14 20:05:28 volumio volumio[985]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:05:28 volumio volumio[985]: info: CorePlayQueue::saveQueue Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::updateTrackBlock Apr 14 20:05:28 volumio volumio[985]: info: CorePlayQueue::getTrackBlock Apr 14 20:05:28 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::play index 0 Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::stop Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 14 20:05:28 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:28 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:30 volumio volumio[985]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 14 20:05:32 volumio volumio[985]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 14 20:05:32 volumio volumio[985]: info: Preparing to generate the ALSA configuration file Apr 14 20:05:32 volumio volumio[985]: info: Asound.conf file unchanged, so no further update is needed Apr 14 20:05:32 volumio volumio[985]: info: Output device has changed, restarting MPD Apr 14 20:05:32 volumio sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 20:05:32 volumio volumio[985]: info: Output device has changed, restarting Shairport Sync Apr 14 20:05:32 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 20:05:32 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:32 volumio sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 20:05:32 volumio sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:32 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 20:05:32 volumio volumio[985]: info: ___________ START PLUGINS ___________ Apr 14 20:05:32 volumio volumio[985]: info: ControllerMpd::onStart: Initializing MPD Apr 14 20:05:32 volumio volumio[985]: info: Creating MPD Configuration file Apr 14 20:05:32 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 20:05:32 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 20:05:32 volumio volumio[985]: info: [1713099932866] CoreMusicLibrary::Adding element Media Servers Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 20:05:32 volumio sudo[1162]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:32 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 20:05:32 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:32 volumio systemd[1]: Listening on mpd.socket. Apr 14 20:05:32 volumio systemd[1]: Starting Music Player Daemon... Apr 14 20:05:32 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 20:05:32 volumio systemd[1]: mpd.service: Succeeded. Apr 14 20:05:32 volumio systemd[1]: Stopped Music Player Daemon. Apr 14 20:05:32 volumio systemd[1]: Starting Music Player Daemon... Apr 14 20:05:32 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 20:05:32 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 20:05:32 volumio volumio[985]: info: [1713099932961] CoreMusicLibrary::Adding element Last_100 Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 20:05:32 volumio volumio[985]: info: [1713099932964] CoreMusicLibrary::Adding element Webradio Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 20:05:32 volumio volumio[985]: info: Creating Spotify config file Apr 14 20:05:32 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: Volumio Calling Home Apr 14 20:05:33 volumio volumio[985]: info: MPD Permissions set Apr 14 20:05:33 volumio volumio[985]: info: MPD Permissions set Apr 14 20:05:33 volumio volumio[985]: info: Spotify config file written Apr 14 20:05:33 volumio volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 14 20:05:33 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 20:05:33 volumio volumio[985]: info: Starting Shairport Sync Apr 14 20:05:33 volumio systemd[1]: Started go-librespot Daemon. Apr 14 20:05:33 volumio go-librespot[1189]: Librespot-go daemon starting... Apr 14 20:05:33 volumio volumio[985]: info: Starting Shairport Sync Apr 14 20:05:33 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:33 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 20:05:33 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:33 volumio volumio[985]: info: Starting Shairport Sync Apr 14 20:05:33 volumio sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 20:05:33 volumio sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 20:05:33 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 14 20:05:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 20:05:33 volumio sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 20:05:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 20:05:33 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:33 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:33 volumio sudo[1194]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 20:05:33 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 14 20:05:33 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 14 20:05:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 20:05:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 20:05:33 volumio sudo[1198]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:33 volumio volumio[985]: info: Shairport-Sync Started Apr 14 20:05:33 volumio volumio[985]: Error adding Membership: Error: addMembership EINVAL Apr 14 20:05:33 volumio volumio[985]: info: Shairport-Sync Started Apr 14 20:05:33 volumio volumio[985]: info: Shairport-Sync Started Apr 14 20:05:33 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:05:33 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:33 volumio go-librespot[1189]: time="2024-04-14T20:05:33+07:00" level=info msg="generated new device id: e4c3d1d4b0ec8c460f9548b2de74c02576e199bb" Apr 14 20:05:33 volumio go-librespot[1189]: time="2024-04-14T20:05:33+07:00" level=debug msg="stored credentials found for jp5ogb1704lrg7mfuyfcd7tcc" Apr 14 20:05:33 volumio go-librespot[1189]: time="2024-04-14T20:05:33+07:00" level=debug msg="obtained new client token: AAAx1inaSASEALJTNsA1EGi7AnD9SmamXZuxBlfo3jd9eY0envR10mXxeOuFUdItkLLVVUhG+Xz75Jtg5UYdo4rJckrh5CTXTwQJ2Xdkl3SvVA6dQ6UcJsOhpHbnhYGsgkOtqxfjyy1Gi4hNq7hGFWYxbB3h+mQYPO0l+P/AUEt6dEV0D3aBq1KwrrGMJIaAVoi7XJ6G5abkyE75aAVYUbbIQxoSnfeoL9a4bcsSuGWJlRR7eiDVJXu9rhbB5Q==" Apr 14 20:05:34 volumio go-librespot[1189]: time="2024-04-14T20:05:34+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 20:05:34 volumio mpd[1171]: Apr 14 20:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 20:05:34 volumio systemd[1]: Started Music Player Daemon. Apr 14 20:05:34 volumio sudo[1154]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:34 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:34 volumio volumio[985]: info: Completed starting Core Plugins Apr 14 20:05:34 volumio volumio[985]: info: ------------------------------------------- Apr 14 20:05:34 volumio volumio[985]: info: ----- MyVolumio plugins startup ---- Apr 14 20:05:34 volumio volumio[985]: info: ------------------------------------------- Apr 14 20:05:34 volumio volumio[985]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 14 20:05:34 volumio volumio[985]: error: MPD error: The expression evaluated to a falsy value: Apr 14 20:05:34 volumio volumio[985]: assert.ok(self.idling) Apr 14 20:05:34 volumio volumio[985]: error: The expression evaluated to a falsy value: Apr 14 20:05:34 volumio volumio[985]: assert.ok(self.idling) Apr 14 20:05:34 volumio volumio[985]: error: updateQueue error: null Apr 14 20:05:34 volumio volumio[985]: info: MPD running with PID1171 Apr 14 20:05:34 volumio volumio[985]: ,establishing connection Apr 14 20:05:34 volumio volumio[985]: error: updateQueue error: null Apr 14 20:05:34 volumio volumio[985]: info: Volumio called home Apr 14 20:05:35 volumio volumio[985]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 14 20:05:35 volumio volumio[985]: SPOTIFY: BQAOzwbN8JE-CKsLdUAFyCNaAgc4urfPuuUvUdH3NyfMmOuLQjcSG5COWQdW6htzpVLdiz8mc9-1A7GDDbd2lWG_RHDClZiM7ueZEdpY-EwfUh7OtNTxOEerTqabKLXVuFvy-GjcP3nm4DmfOMVh934f0euUnl0xxUNlcVbLGNyOKh-rspHo2UnFpjqdqusQFil4zT5UA00oW_RxetP5HQwILZhDEB6DNTSqF5I2x9NSEpgSbJwsySZUxaVM9ymTfNbAo4QyhDseDX3KwA Apr 14 20:05:35 volumio volumio[985]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 14 20:05:35 volumio volumio[985]: info: New Spotify access token = BQAOzwbN8JE-CKsLdUAFyCNaAgc4urfPuuUvUdH3NyfMmOuLQjcSG5COWQdW6htzpVLdiz8mc9-1A7GDDbd2lWG_RHDClZiM7ueZEdpY-EwfUh7OtNTxOEerTqabKLXVuFvy-GjcP3nm4DmfOMVh934f0euUnl0xxUNlcVbLGNyOKh-rspHo2UnFpjqdqusQFil4zT5UA00oW_RxetP5HQwILZhDEB6DNTSqF5I2x9NSEpgSbJwsySZUxaVM9ymTfNbAo4QyhDseDX3KwA Apr 14 20:05:35 volumio volumio[985]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 14 20:05:35 volumio volumio[985]: SPOTIFY: User informations: {"display_name":"Đinh Khánh Phương","external_urls":{"spotify":"https://open.spotify.com/user/jp5ogb1704lrg7mfuyfcd7tcc"},"href":"https://api.spotify.com/v1/users/jp5ogb1704lrg7mfuyfcd7tcc","id":"jp5ogb1704lrg7mfuyfcd7tcc","images":[{"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=1933883059957325&height=50&width=50&ext=1715663821&hash=Aba1IELze3sOQZDQ2d-X1uSJ","height":64,"width":64},{"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=1933883059957325&height=300&width=300&ext=1715663821&hash=AbYgXYzbjmD3k9jKUgQeUNFm","height":300,"width":300}],"type":"user","uri":"spotify:user:jp5ogb1704lrg7mfuyfcd7tcc","followers":{"href":null,"total":0},"country":"VN","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"phuongdk@gmail.com"} Apr 14 20:05:35 volumio volumio[985]: info: Spotify Successfully logged in Apr 14 20:05:35 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 20:05:35 volumio volumio[985]: info: [1713099935621] CoreMusicLibrary::Adding element Spotify Apr 14 20:05:35 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 20:05:35 volumio volumio[985]: Cannot find translation for source Spotify Apr 14 20:05:36 volumio volumio[985]: info: go-librespot daemon successfully initialized Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::ClearQueue Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::stop Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::clearPlayQueue Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::saveQueue Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::addQueueItems Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::addQueueItems Apr 14 20:05:36 volumio volumio[985]: info: Preload queue cleared Apr 14 20:05:36 volumio volumio[985]: info: Adding Item to queue: music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013) Apr 14 20:05:36 volumio volumio[985]: info: Exploding uri music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013) in service mpd Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F01%20-%20Ich%20dich%20liebe%20(China%20Forbes).flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F02%20-%20Quizas%20quizas%20quizas%20(Storn%20Lar.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/02 - Quizas quizas quizas (Storn Lar.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F03%20-%20I'm%20Waiting%20For%20You%20(Meow%20Meow).flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/03 - I'm Waiting For You (Meow Meow).flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F04%20-%20Omide%20zendegani%20(Storm%20Large).flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/04 - Omide zendegani (Storm Large).flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F05%20-%20Yo%20te%20quiero%20siempre%20(Ari%20Shapi.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/05 - Yo te quiero siempre (Ari Shapi.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F06%20-%20Je%20ne%20t'aime%20plus%20(China%20Forbes.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/06 - Je ne t'aime plus (China Forbes.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F07%20-%20Zundoko%20Bushi%20(Timothy%20Mishimot.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/07 - Zundoko Bushi (Timothy Mishimot.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F08%20-%20Oana%20Cand%20Nu%20Te%20Iubean%20(Storm%20L.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/08 - Oana Cand Nu Te Iubean (Storm L.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F09%20-%20She%20Was%20Too%20Good%20To%20Me%20(Robert.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/09 - She Was Too Good To Me (Robert.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F10%20-%20Uskudar%20(China%20Forbes).flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/10 - Uskudar (China Forbes).flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F11%20-%20Sway%20(Storm%20Large).flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/11 - Sway (Storm Large).flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F12%20-%20Kitty%20Come%20Home%20(with%20Rufus%20Wai.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/12 - Kitty Come Home (with Rufus Wai.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F13%20-%20What'll%20I%20do%20(China%20Forbes).flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/13 - What'll I do (China Forbes).flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F14%20-%20Get%20HappyHappy%20Days%20(China%20For.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/14 - Get HappyHappy Days (China For.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F15%20-%20Heliotrope%20Bouquet%20(instrumenta.flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/15 - Heliotrope Bouquet (instrumenta.flac Apr 14 20:05:36 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Pink%20Martini/Get%20Happy/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPink%20Martini%20-%20Get%20Happy%20(2013)%2F16%20-%20Smile%20(Phyllis%20Diller).flac&metadata=false Apr 14 20:05:36 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/16 - Smile (Phyllis Diller).flac Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::saveQueue Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::updateTrackBlock Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrackBlock Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::play index 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::stop Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::clearAddPlayTracks USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand stop took 2 milliseconds Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces system playlist update Apr 14 20:05:36 volumio volumio[985]: info: Ignoring MPD Status Update Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand clear took 2 milliseconds Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand add "USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac" Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces system playlist update Apr 14 20:05:36 volumio volumio[985]: info: Ignoring MPD Status Update Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces system playlist update Apr 14 20:05:36 volumio volumio[985]: info: Ignoring MPD Status Update Apr 14 20:05:36 volumio volumio[985]: error: updateQueue error: null Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces system playlist update Apr 14 20:05:36 volumio volumio[985]: info: Ignoring MPD Status Update Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 7ms Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand add "USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac" took 6 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 4ms Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 3ms Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand play Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces system playlist update Apr 14 20:05:36 volumio volumio[985]: info: Ignoring MPD Status Update Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces system playlist update Apr 14 20:05:36 volumio volumio[985]: info: Ignoring MPD Status Update Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 7ms Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand play took 5 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 5ms Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 5ms Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::getState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::getState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::getState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand status took 37 milliseconds Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::getState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand status took 41 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand status took 39 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseTrackInfo Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::pushState Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::servicePushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ich dich liebe (China Forbes)","artist":"Pink Martini","album":"Get Happy","uri":"USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac","trackType":"flac"} Apr 14 20:05:36 volumio volumio[985]: verbose: CURRENT POSITION 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState stateService play Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState currentStatus stop Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 57ms Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::getState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:05:36 volumio volumio[985]: info: Apr 14 20:05:36 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::getState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand status took 18 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand status took 5 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand status took 4 milliseconds Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseTrackInfo Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseTrackInfo Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseState Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::pushState Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::servicePushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Ich dich liebe (China Forbes)","artist":"Pink Martini","album":"Get Happy","uri":"USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac","trackType":"flac"} Apr 14 20:05:36 volumio volumio[985]: verbose: CURRENT POSITION 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState stateService play Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState currentStatus play Apr 14 20:05:36 volumio volumio[985]: info: Received an update from plugin. extracting info from payload Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::pushState Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::servicePushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: verbose: STATE SERVICE {"status":"play","position":0,"seek":205,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"533 Kbps","isStreaming":false,"title":"Ich dich liebe (China Forbes)","artist":"Pink Martini","album":"Get Happy","uri":"USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac","trackType":"flac"} Apr 14 20:05:36 volumio volumio[985]: verbose: CURRENT POSITION 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState stateService play Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState currentStatus play Apr 14 20:05:36 volumio volumio[985]: info: Received an update from plugin. extracting info from payload Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 95ms Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 94ms Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 14 20:05:36 volumio volumio[985]: info: sendMpdCommand playlistinfo took 34 milliseconds Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseTrackInfo Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseTrackInfo Apr 14 20:05:36 volumio volumio[985]: verbose: ControllerMpd::parseTrackInfo Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::pushState Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::servicePushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"788 Kbps","isStreaming":false,"title":"Ich dich liebe (China Forbes)","artist":"Pink Martini","album":"Get Happy","uri":"USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac","trackType":"flac"} Apr 14 20:05:36 volumio volumio[985]: verbose: CURRENT POSITION 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState stateService play Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState currentStatus play Apr 14 20:05:36 volumio volumio[985]: info: Received an update from plugin. extracting info from payload Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::pushState Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::servicePushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"788 Kbps","isStreaming":false,"title":"Ich dich liebe (China Forbes)","artist":"Pink Martini","album":"Get Happy","uri":"USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac","trackType":"flac"} Apr 14 20:05:36 volumio volumio[985]: verbose: CURRENT POSITION 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState stateService play Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState currentStatus play Apr 14 20:05:36 volumio volumio[985]: info: Received an update from plugin. extracting info from payload Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: ControllerMpd::pushState Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::servicePushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"788 Kbps","isStreaming":false,"title":"Ich dich liebe (China Forbes)","artist":"Pink Martini","album":"Get Happy","uri":"USB/DKPhuong_USB/Songs/Quoc te/World Music/Pink Martini - Get Happy (2013)/01 - Ich dich liebe (China Forbes).flac","trackType":"flac"} Apr 14 20:05:36 volumio volumio[985]: verbose: CURRENT POSITION 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState stateService play Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::syncState currentStatus play Apr 14 20:05:36 volumio volumio[985]: info: Received an update from plugin. extracting info from payload Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:36 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:36 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 89ms Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 76ms Apr 14 20:05:36 volumio volumio[985]: info: ------------------------------ 73ms Apr 14 20:05:39 volumio volumio[985]: info: Initializing connection to go-librespot Websocket Apr 14 20:05:39 volumio go-librespot[1189]: time="2024-04-14T20:05:39+07:00" level=debug msg="new websocket client" Apr 14 20:05:39 volumio volumio[985]: info: Connection to go-librespot Websocket established Apr 14 20:05:39 volumio go-librespot[1189]: time="2024-04-14T20:05:39+07:00" level=debug msg="completed keyexchange" Apr 14 20:05:39 volumio go-librespot[1189]: time="2024-04-14T20:05:39+07:00" level=debug msg="completed challenge" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="authenticated as jp5ogb1704lrg7mfuyfcd7tcc" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="authenticated as jp5ogb1704lrg7mfuyfcd7tcc" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="dealer connection opened" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Apr 14 20:05:40 volumio go-librespot[1189]: time="2024-04-14T20:05:40+07:00" level=debug msg="received connection id: ZmI2YTBmNDktMTdlZi00YzE4LTliY2MtYTA1NzI4ZTAyN2Q2K2RlYWxlcit0Y3A6Ly9nYWUyLWRlYWxlci1hLXpjcTcuZ2FlMi5zcG90aWZ5Lm5ldDo1NzAwK0JBOTAxNzBCNzg1QTIxNTI1ODY3QTQ0NDc0NEFBNTU3M0E5NUZFOEU2NUZENDRDMjZBMzVBOERBNTZDRjMyRjE=" Apr 14 20:05:41 volumio go-librespot[1189]: time="2024-04-14T20:05:41+07:00" level=debug msg="put connect state because NEW_DEVICE" Apr 14 20:05:42 volumio volumio[985]: info: Getting Spotify volume Apr 14 20:05:42 volumio volumio[985]: info: Spotify volume: 100 Apr 14 20:05:42 volumio volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Apr 14 20:05:42 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 20:05:42 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 14 20:05:43 volumio volumio[985]: info: Adding plugin bluetooth to MyMusic Plugins Apr 14 20:05:43 volumio volumio[985]: info: Adding plugin multiroom to MyMusic Plugins Apr 14 20:05:43 volumio volumio[985]: info: Adding plugin metavolumio to MyMusic Plugins Apr 14 20:05:43 volumio volumio[985]: info: Adding plugin cd_controller to MyMusic Plugins Apr 14 20:05:43 volumio volumio[985]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 14 20:05:43 volumio volumio[985]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 14 20:05:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 20:05:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 20:05:43 volumio volumio[985]: info: Starting MyVolumio Remote Streaming Endpoints Apr 14 20:05:43 volumio volumio[985]: info: MyVolumio login type: Token Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 14 20:05:43 volumio volumio[985]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 14 20:05:44 volumio volumio[985]: info: Starting Streaming Service Transparent Proxy Apr 14 20:05:44 volumio volumio[985]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 14 20:05:44 volumio volumio[985]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 14 20:05:44 volumio volumio[985]: info: Streaming services startup Apr 14 20:05:44 volumio volumio[985]: info: Starting Streaming Daemon Apr 14 20:05:44 volumio sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 14 20:05:44 volumio sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:44 volumio sudo[1224]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:44 volumio volumio[985]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 14 20:05:44 volumio volumio[985]: error: Cannot start Volumio Streaming Daemon Apr 14 20:05:44 volumio volumio[985]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 14 20:05:44 volumio volumio[985]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 14 20:05:45 volumio volumio[985]: STREAMING PROXY: Starting server on port 3245 Apr 14 20:05:45 volumio volumio[985]: Node JS runtime: 14 Apr 14 20:05:46 volumio volumio[985]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 14 20:05:46 volumio volumio[985]: info: MyVolumio token set successfully Apr 14 20:05:46 volumio volumio[985]: info: MYVOLUMIO: Adding device Apr 14 20:05:46 volumio volumio[985]: info: MYVOLUMIO: Evaluating Server Apr 14 20:05:47 volumio volumio[985]: info: MyVolumio status changed Apr 14 20:05:47 volumio volumio[985]: info: Streaming services startup Apr 14 20:05:47 volumio volumio[985]: info: Starting Streaming Daemon Apr 14 20:05:47 volumio volumio[985]: info: Removing browser output: myVolumio user plan is not superstar Apr 14 20:05:47 volumio volumio[985]: info: Removing audio output: Apr 14 20:05:47 volumio volumio[985]: info: Stoppping Tunnel 1 Apr 14 20:05:47 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 14 20:05:47 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:47 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:47 volumio volumio[985]: error: Cannot start Volumio Streaming Daemon Apr 14 20:05:47 volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 14 20:05:47 volumio volumio[985]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 14 20:05:47 volumio volumio[985]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 14 20:05:47 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:05:47 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Apr 14 20:05:47 volumio volumio[985]: info: Remote SSH Stopped Apr 14 20:05:48 volumio volumio[1150]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 14 20:05:51 volumio volumio[985]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 20:05:51 volumio volumio[985]: info: CoreStateMachine::ClearQueue Apr 14 20:05:51 volumio volumio[985]: info: CoreStateMachine::stop Apr 14 20:05:51 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:05:51 volumio volumio[985]: info: CoreStateMachine::stPlaybackTimer Apr 14 20:05:51 volumio volumio[985]: info: CoreStateMachine::updateTrackBlock Apr 14 20:05:51 volumio volumio[985]: info: CorePlayQueue::getTrackBlock Apr 14 20:05:51 volumio volumio[985]: info: CoreStateMachine::pushState Apr 14 20:05:51 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:51 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:05:51 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 14 20:05:51 volumio volumio[985]: info: CoreStateMachine::serviceStop Apr 14 20:05:51 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 20:05:51 volumio volumio[985]: info: CoreCommandRouter::serviceStop Apr 14 20:05:51 volumio volumio[985]: info: ControllerMpd::stop Apr 14 20:05:51 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 20:05:51 volumio volumio[985]: info: CorePlayQueue::clearPlayQueue Apr 14 20:05:51 volumio volumio[985]: info: CorePlayQueue::saveQueue Apr 14 20:05:51 volumio volumio[985]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:05:51 volumio volumio[985]: info: CoreStateMachine::addQueueItems Apr 14 20:05:51 volumio volumio[985]: info: CorePlayQueue::addQueueItems Apr 14 20:05:51 volumio volumio[985]: info: Preload queue cleared Apr 14 20:05:51 volumio volumio[985]: info: Adding Item to queue: music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003) Apr 14 20:05:51 volumio volumio[985]: info: Exploding uri music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003) in service mpd Apr 14 20:05:51 volumio volumio[985]: info: sendMpdCommand stop took 42 milliseconds Apr 14 20:05:51 volumio volumio[985]: info: Apr 14 20:05:51 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:51 volumio volumio[985]: info: ControllerMpd::getState Apr 14 20:05:51 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:05:51 volumio volumio[985]: info: Apr 14 20:05:51 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:51 volumio volumio[985]: info: ControllerMpd::getState Apr 14 20:05:51 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:05:51 volumio volumio[985]: info: Apr 14 20:05:51 volumio volumio[985]: ---------------------------- MPD announces state update: player Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Salsa%20Celtica/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F01%20-%20Salsa%20Celtica%20-%20El%20Sol%20De%20La%20Noche%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/01 - Salsa Celtica - El Sol De La Noche .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Havana%20Mambo/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F02%20-%20Havana%20Mambo%20-%20Malani%C3%B1a%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/02 - Havana Mambo - Malaniña .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Arnell%20I%20Su%20Orkesta/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F03%20-%20Arnell%20I%20Su%20Orkesta%20-%20Ban%20Sali%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/03 - Arnell I Su Orkesta - Ban Sali .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Apurimac/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F04%20-%20Apurimac%20-%20Forest%20Flower%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/04 - Apurimac - Forest Flower .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Tour%C3%A9%20Kunda/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F05%20-%20Tour%C3%A9%20Kunda%20-%20Rapada%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/05 - Touré Kunda - Rapada .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Mousta%20Largo/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F06%20-%20Mousta%20Largo%20-%20Anna%20Maria%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/06 - Mousta Largo - Anna Maria .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Shaan/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F07%20-%20Shaan%20-%20Mana%20Janab%20Ne%20Pukara%20Nahin%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/07 - Shaan - Mana Janab Ne Pukara Nahin .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Babacar%20Y%20Sabor%20Internacional/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F08%20-%20Babacar%20Y%20Sabor%20Internacional%20-%20Ignane%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/08 - Babacar Y Sabor Internacional - Ignane .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Orquesta%20De%20La%20Luz/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F09%20-%20Orquesta%20De%20La%20Luz%20-%20Flores%20Y%20Tambores%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/09 - Orquesta De La Luz - Flores Y Tambores .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Richard%20Bona/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F10%20-%20Richard%20Bona%20-%20Te%20Dikalo%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/10 - Richard Bona - Te Dikalo .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=El%20Septeto/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F11%20-%20El%20Septeto%20-%20Ru%C3%B1idera%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/11 - El Septeto - Ruñidera .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Haitiando/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2F12%20-%20Haitiando%20-%20Pa%20Koute%20Kons%C3%A8y%20.flac&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/12 - Haitiando - Pa Koute Konsèy .flac Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Salsa%20Celtica/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2FPutumayo%20Presents%20%20Salsa%20Around%20The%20World.cue&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/Putumayo Presents Salsa Around The World.cue Apr 14 20:05:51 volumio volumio[985]: info: ALBUMART /albumart?cacheid=608&web=Salsa%20Celtica/Putumayo%20Presents%3A%20Salsa%20Around%20The%20World/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20Salsa%20Around%20The%20World%20(2003)%2FPutumayo%20Presents%20%20Salsa%20Around%20The%20World.cue&metadata=false Apr 14 20:05:51 volumio volumio[985]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - Salsa Around The World (2003)/Putumayo Presents Salsa Around The World.cue Apr 14 20:05:51 volumio volumio[985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:05:51 volumio volumio[985]: Error: Unable to resolve or reject the same promise twice Apr 14 20:05:51 volumio volumio[985]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 14 20:05:51 volumio volumio[985]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 14 20:05:51 volumio volumio[985]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 14 20:05:51 volumio volumio[985]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 14 20:05:51 volumio volumio[985]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 14 20:05:51 volumio volumio[985]: at Socket.emit (events.js:315:20) Apr 14 20:05:51 volumio volumio[985]: at addChunk (internal/streams/readable.js:309:12) Apr 14 20:05:51 volumio volumio[985]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 14 20:05:51 volumio volumio[985]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 14 20:05:51 volumio volumio[985]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 14 20:05:51 volumio volumio[985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:05:51 volumio sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 20:04 Apr 14 20:05:51 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"