-- Logs begin at Sat 2025-12-13 12:15:39 CET, end at Sat 2025-12-13 12:19:59 CET. --
Dec 13 12:18:01 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:18:01 volumio sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 13 12:18:01 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:01 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:01 volumio sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 13 12:18:01 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:01 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:01 volumio sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 13 12:18:01 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:01 volumio sudo[1874]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:01 volumio volumio[1729]: info: Upmpdcli Daemon Started
Dec 13 12:18:02 volumio volumio-remote-updater[522]: [2025-12-13 12:18:02] [connect] Successful connection
Dec 13 12:18:02 volumio volumio-remote-updater[522]: [2025-12-13 12:18:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765624682 101
Dec 13 12:18:02 volumio volumio[1729]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 9
Dec 13 12:18:03 volumio volumio[1729]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 10
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 13 12:18:03 volumio volumio[1729]: verbose: New Socket.io Connection to 192.168.100.5 from 192.168.100.6 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36 Total Clients: 11
Dec 13 12:18:03 volumio volumio[1729]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Dec 13 12:18:03 volumio volumio[1729]: info: Received Get System Version
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 13 12:18:03 volumio volumio[1729]: info: Received Get System Info
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 13 12:18:03 volumio volumio[1729]: info: Discovery: Getting this device information
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 13 12:18:03 volumio volumio[1729]: info: Received Get System Info
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 13 12:18:03 volumio volumio[1729]: info: Discovery: Getting this device information
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:03 volumio volumio[1729]: info: Listing playlists
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetQueue
Dec 13 12:18:03 volumio volumio[1729]: info: CoreStateMachine::getQueue
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getQueue
Dec 13 12:18:03 volumio volumio[1729]: info: Listing playlists
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 13 12:18:03 volumio volumio[1729]: info: Received Get System Info
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 13 12:18:03 volumio volumio[1729]: info: Discovery: Getting this device information
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 13 12:18:03 volumio volumio[1729]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:03 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:06 volumio volumio[1729]: info: CoreCommandRouter::volumioPlay
Dec 13 12:18:06 volumio volumio[1729]: info: CoreStateMachine::play index undefined
Dec 13 12:18:06 volumio volumio[1729]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:06 volumio volumio[1729]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:08 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:18:15 volumio volumio[1729]: info: Preload queue cleared
Dec 13 12:18:15 volumio volumio[1729]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 13 12:18:15 volumio volumio[1729]: info: CoreStateMachine::ClearQueue
Dec 13 12:18:15 volumio volumio[1729]: info: CoreStateMachine::stop
Dec 13 12:18:15 volumio volumio[1729]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:15 volumio volumio[1729]: info: CorePlayQueue::clearPlayQueue
Dec 13 12:18:15 volumio volumio[1729]: info: CorePlayQueue::saveQueue
Dec 13 12:18:15 volumio volumio[1729]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:18:15 volumio volumio[1729]: info: CoreStateMachine::addQueueItems
Dec 13 12:18:15 volumio volumio[1729]: info: CorePlayQueue::addQueueItems
Dec 13 12:18:15 volumio volumio[1729]: info: Preload queue cleared
Dec 13 12:18:15 volumio volumio[1729]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/KRUTЬ - Літепло _ Full album_256k.mp3
Dec 13 12:18:15 volumio volumio[1729]: info: Exploding uri music-library/USB/6745-2301/JAZZ/KRUTЬ - Літепло _ Full album_256k.mp3 in service mpd
Dec 13 12:18:15 volumio volumio[1729]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 13 12:18:15 volumio volumio[1729]: TypeError: Cannot read property 'then' of undefined
Dec 13 12:18:15 volumio volumio[1729]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19)
Dec 13 12:18:15 volumio volumio[1729]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21)
Dec 13 12:18:15 volumio volumio[1729]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16)
Dec 13 12:18:15 volumio volumio[1729]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24)
Dec 13 12:18:15 volumio volumio[1729]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19)
Dec 13 12:18:15 volumio volumio[1729]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30)
Dec 13 12:18:15 volumio volumio[1729]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25)
Dec 13 12:18:15 volumio volumio[1729]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23)
Dec 13 12:18:15 volumio volumio[1729]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17)
Dec 13 12:18:15 volumio volumio[1729]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33)
Dec 13 12:18:15 volumio volumio[1729]: at Socket.emit (events.js:315:20)
Dec 13 12:18:15 volumio volumio[1729]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Dec 13 12:18:15 volumio volumio[1729]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Dec 13 12:18:15 volumio volumio[1729]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 13 12:18:16 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:18:16 volumio sudo[1926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-13 12:17
Dec 13 12:18:16 volumio sudo[1926]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:16 volumio sudo[1926]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:17 volumio volumio-remote-updater[522]: [2025-12-13 12:18:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Dec 13 12:18:17 volumio volumio-remote-updater[522]: [2025-12-13 12:18:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Dec 13 12:18:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Dec 13 12:18:17 volumio systemd[1]: media-6745\x2d2301.mount: Succeeded.
Dec 13 12:18:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Dec 13 12:18:17 volumio systemd[1]: Started dynamicswap service.
Dec 13 12:18:17 volumio systemd[1]: dynamicswap.service: Succeeded.
Dec 13 12:18:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Dec 13 12:18:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2.
Dec 13 12:18:17 volumio systemd[1]: Stopped Volumio Backend Module.
Dec 13 12:18:17 volumio systemd[1]: Started Volumio Backend Module.
Dec 13 12:18:17 volumio systemd[1]: Started dynamicswap service.
Dec 13 12:18:17 volumio systemd[1]: dynamicswap.service: Succeeded.
Dec 13 12:18:19 volumio volumio[1941]: info: -------------------------------------------
Dec 13 12:18:19 volumio volumio[1941]: info: ----- Volumio3 ----
Dec 13 12:18:19 volumio volumio[1941]: info: -------------------------------------------
Dec 13 12:18:19 volumio volumio[1941]: info: ----- System startup ----
Dec 13 12:18:19 volumio volumio[1941]: info: -------------------------------------------
Dec 13 12:18:20 volumio volumio[1941]: info: MYVOLUMIO Environment detected
Dec 13 12:18:20 volumio volumio[1941]: info: Plugin folders cleanup
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning into folder /volumio/app/plugins/
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning category audio_interface
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning category miscellanea
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning category music_service
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning category plugins.json
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning category system_controller
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning category user_interface
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning into folder /data/plugins/
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning category system_controller
Dec 13 12:18:20 volumio volumio[1941]: info: Scanning category user_interface
Dec 13 12:18:20 volumio volumio[1941]: info: Plugin folders cleanup completed
Dec 13 12:18:20 volumio volumio[1941]: info: -------------------------------------------
Dec 13 12:18:20 volumio volumio[1941]: info: ----- Core plugins startup ----
Dec 13 12:18:20 volumio volumio[1941]: info: -------------------------------------------
Dec 13 12:18:20 volumio volumio[1941]: info: Loading plugins from folder /volumio/app/plugins/
Dec 13 12:18:20 volumio volumio[1941]: info: Adding plugin upnp to MyMusic Plugins
Dec 13 12:18:20 volumio volumio[1941]: info: Adding plugin airplay_emulation to MyMusic Plugins
Dec 13 12:18:20 volumio volumio[1941]: info: Adding plugin upnp_browser to MyMusic Plugins
Dec 13 12:18:20 volumio volumio[1941]: info: Loading plugins from folder /data/plugins/
Dec 13 12:18:20 volumio volumio[1941]: info: Loading plugin "system"...
Dec 13 12:18:20 volumio volumio[1941]: info: Loading plugin "appearance"...
Dec 13 12:18:21 volumio volumio[1941]: info: Loading plugin "network"...
Dec 13 12:18:21 volumio volumio[1941]: info: Refreshing Cached IP Addresses
Dec 13 12:18:21 volumio sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 13 12:18:21 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:21 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:21 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 13 12:18:21 volumio volumio[1941]: info: Loading plugin "services"...
Dec 13 12:18:21 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:21 volumio volumio[1941]: info: Loading plugin "alsa_controller"...
Dec 13 12:18:21 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:21 volumio sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Dec 13 12:18:21 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:21 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:21 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 13 12:18:21 volumio volumio[1941]: info: Loading plugin "wizard"...
Dec 13 12:18:21 volumio volumio[1941]: info: Loading plugin "networkfs"...
Dec 13 12:18:21 volumio volumio[1941]: info: Starting Udev Watcher for removable devices
Dec 13 12:18:21 volumio volumio[1941]: info: Ignoring mount for partition: boot
Dec 13 12:18:21 volumio volumio[1941]: info: Ignoring mount for partition: volumio
Dec 13 12:18:21 volumio volumio[1941]: info: Ignoring mount for partition: volumio_data
Dec 13 12:18:21 volumio volumio[1941]: info: Mounting Device 06D7-9332
Dec 13 12:18:21 volumio sudo[1982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/06D7-9332 -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Dec 13 12:18:21 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:21 volumio sudo[1982]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:21 volumio volumio[1941]: mount: /media/06D7-9332: /dev/sdb1 already mounted on /media/06D7-9332.
Dec 13 12:18:21 volumio volumio[1941]: error: Failed to mount 06D7-9332: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/06D7-9332" -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Dec 13 12:18:21 volumio volumio[1941]: mount: /media/06D7-9332: /dev/sdb1 already mounted on /media/06D7-9332.
Dec 13 12:18:21 volumio volumio[1941]: info: Mounting Device 6745-2301
Dec 13 12:18:21 volumio sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/6745-2301 -o noatime
Dec 13 12:18:21 volumio sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:22 volumio volumio-remote-updater[522]: [2025-12-13 12:18:22] [connect] Successful connection
Dec 13 12:18:22 volumio sudo[1988]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:22 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 13 12:18:22 volumio volumio[1941]: info: Loading plugin "volumio_command_line_client"...
Dec 13 12:18:22 volumio volumio[1941]: info: Loading plugin "upnp"...
Dec 13 12:18:22 volumio volumio[1941]: info: [1765624702096] Starting Upmpd Daemon
Dec 13 12:18:22 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 13 12:18:22 volumio volumio[1941]: info: Loading plugin "my_music"...
Dec 13 12:18:22 volumio volumio[1941]: info: Loading plugin "mpd"...
Dec 13 12:18:22 volumio volumio[1941]: info: Loading plugin "upnp_browser"...
Dec 13 12:18:22 volumio volumio[1941]: info: Loading plugin "alarm-clock"...
Dec 13 12:18:23 volumio volumio[1941]: info: Loading plugin "airplay_emulation"...
Dec 13 12:18:23 volumio volumio[1941]: info: Starting Shairport Sync
Dec 13 12:18:23 volumio volumio[1941]: info: Loading plugin "last_100"...
Dec 13 12:18:23 volumio volumio[1941]: info: Loading plugin "webradio"...
Dec 13 12:18:23 volumio volumio[1941]: info: Loading plugin "i2s_dacs"...
Dec 13 12:18:23 volumio volumio[1941]: info: Loading plugin "volumiodiscovery"...
Dec 13 12:18:23 volumio volumio[1941]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 13 12:18:23 volumio node[1941]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 13 12:18:23 volumio volumio[1941]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 13 12:18:23 volumio node[1941]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 13 12:18:23 volumio volumio[1941]: *** WARNING *** For more information see
Dec 13 12:18:23 volumio node[1941]: *** WARNING *** For more information see
Dec 13 12:18:23 volumio volumio[1941]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 13 12:18:23 volumio node[1941]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 13 12:18:23 volumio volumio[1941]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 13 12:18:23 volumio node[1941]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 13 12:18:23 volumio volumio[1941]: *** WARNING *** For more information see
Dec 13 12:18:23 volumio node[1941]: *** WARNING *** For more information see
Dec 13 12:18:23 volumio volumio[1941]: info: Applying required configuration parameters for plugin volumiodiscovery
Dec 13 12:18:23 volumio volumio[1941]: info: Discovery: Started advertising with name: Volumio
Dec 13 12:18:23 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 13 12:18:23 volumio volumio[1941]: info: Loading plugin "autostart"...
Dec 13 12:18:23 volumio volumio[1941]: info: Applying required configuration parameters for plugin autostart
Dec 13 12:18:23 volumio volumio[1941]: info: AutoStart - onVolumioStart - read config.json
Dec 13 12:18:23 volumio volumio[1941]: info: Loading plugin "peppy_screensaver"...
Dec 13 12:18:23 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:18:24 volumio volumio[1941]: info: Applying required configuration parameters for plugin peppy_screensaver
Dec 13 12:18:24 volumio volumio[1941]: info: Loading plugin "outputs"...
Dec 13 12:18:24 volumio volumio[1941]: info: Loading plugin "albumart"...
Dec 13 12:18:24 volumio volumio[1941]: info: Plugin example_plugin is not enabled
Dec 13 12:18:24 volumio volumio[1941]: info: Loading plugin "inputs"...
Dec 13 12:18:24 volumio volumio[1941]: info: Loading plugin "updater_comm"...
Dec 13 12:18:24 volumio volumio[1941]: info: Plugin mpdemulation is not enabled
Dec 13 12:18:24 volumio volumio[1941]: info: Loading plugin "rest_api"...
Dec 13 12:18:24 volumio volumio[1941]: info: Loading plugin "websocket"...
Dec 13 12:18:24 volumio volumio[1941]: info: Loading plugin "touch_display"...
Dec 13 12:18:24 volumio volumio[1941]: Forking 3 albumart workers
Dec 13 12:18:24 volumio volumio[1941]: info: Applying required configuration parameters for plugin touch_display
Dec 13 12:18:24 volumio volumio[1941]: info: Loading i18n strings for locale ua
Dec 13 12:18:24 volumio volumio[1941]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Dec 13 12:18:24 volumio volumio[1941]: Updating browse sources language
Dec 13 12:18:24 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::initPlayerControls
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 13 12:18:25 volumio volumio[1941]: Express server listening on port 3000
Dec 13 12:18:25 volumio volumio[1941]: [Metrics] WebUI: 6s 507.96ms
Dec 13 12:18:25 volumio volumio[1941]: info: CoreStateMachine::resetVolumioState
Dec 13 12:18:25 volumio volumio[1941]: info: CoreStateMachine::getcurrentVolume
Dec 13 12:18:25 volumio volumio[1941]: info: CoreCommandRouter::volumioRetrievevolume
Dec 13 12:18:25 volumio volumio[1941]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Dec 13 12:18:25 volumio volumio[1941]: wlan0 Interface doesn't support scanning : Device or resource busy
Dec 13 12:18:25 volumio volumio[1941]: info: Cannot use regular scanning, forcing with ap-force
Dec 13 12:18:25 volumio sudo[2033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Dec 13 12:18:25 volumio sudo[2033]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:25 volumio volumio[1941]: Starting albumart workers
Dec 13 12:18:25 volumio volumio[1941]: Starting albumart workers
Dec 13 12:18:26 volumio volumio[1941]: Starting albumart workers
Dec 13 12:18:28 volumio sudo[2033]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:28 volumio volumio[1941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 1
Dec 13 12:18:28 volumio volumio[1941]: verbose: New Socket.io Connection to 192.168.100.5 from 192.168.100.6 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36 Total Clients: 2
Dec 13 12:18:28 volumio volumio[1941]: verbose: New Socket.io Connection to 192.168.100.5 from 192.168.100.6 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36 Total Clients: 3
Dec 13 12:18:28 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:18:28 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:18:28 volumio volumio[1941]: info: CoreStateMachine::updateTrackBlock
Dec 13 12:18:28 volumio volumio[1941]: info: CorePlayQueue::getTrackBlock
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::volumioRetrievevolume
Dec 13 12:18:28 volumio volumio[1941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4
Dec 13 12:18:28 volumio volumio[1941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5
Dec 13 12:18:28 volumio volumio[1941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 6
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:18:28 volumio volumio[1941]: info: Reloading queue from file
Dec 13 12:18:28 volumio volumio[1941]: info: CoreStateMachine::setRepeat false single undefined
Dec 13 12:18:28 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:18:28 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:18:28 volumio volumio[1941]: info: CoreStateMachine::setRandom null
Dec 13 12:18:28 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:18:28 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:18:28 volumio volumio[1941]: info: Setting Device type: Raspberry PI
Dec 13 12:18:28 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:18:28 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:18:28 volumio volumio[1941]: info: Discovery: adding e80d4cac-6b45-4d19-a7cd-fa4a2cb00012
Dec 13 12:18:28 volumio volumio[1941]: info: Discovery: Found device Volumio
Dec 13 12:18:28 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:28 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:30 volumio volumio[1941]: verbose: New Socket.io Connection to 192.168.100.5 from 192.168.100.6 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36 Total Clients: 7
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:30 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:30 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:30 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::volumioGetQueue
Dec 13 12:18:30 volumio volumio[1941]: info: CoreStateMachine::getQueue
Dec 13 12:18:30 volumio volumio[1941]: info: CorePlayQueue::getQueue
Dec 13 12:18:30 volumio volumio[1941]: info: Listing playlists
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 13 12:18:30 volumio volumio[1941]: info: Received Get System Info
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 13 12:18:30 volumio volumio[1941]: info: Discovery: Getting this device information
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:30 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 13 12:18:30 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:30 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:31 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:18:31 volumio volumio[1941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 8
Dec 13 12:18:31 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:32 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 13 12:18:32 volumio volumio[1941]: info: Received Get System Info
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 13 12:18:32 volumio volumio[1941]: info: Discovery: Getting this device information
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:32 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 13 12:18:32 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:32 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:32 volumio volumio[1941]: info: Listing playlists
Dec 13 12:18:32 volumio sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 13 12:18:32 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:32 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:32 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 13 12:18:32 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:32 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:32 volumio sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 13 12:18:32 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:32 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:32 volumio volumio[1941]: info: Upmpdcli Daemon Started
Dec 13 12:18:33 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:18:33 volumio volumio[1941]: info: CURURI: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:18:33 volumio volumio[1941]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
Dec 13 12:18:34 volumio volumio-remote-updater[522]: [2025-12-13 12:18:34] [connect] Successful connection
Dec 13 12:18:34 volumio volumio-remote-updater[522]: [2025-12-13 12:18:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765624714 101
Dec 13 12:18:34 volumio volumio[1941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 9
Dec 13 12:18:34 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:18:34 volumio volumio[1941]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::ClearQueue
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:34 volumio volumio[1941]: info: CorePlayQueue::clearPlayQueue
Dec 13 12:18:34 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:18:34 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::addQueueItems
Dec 13 12:18:34 volumio volumio[1941]: info: CorePlayQueue::addQueueItems
Dec 13 12:18:34 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:18:34 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:18:34 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974) in service mpd
Dec 13 12:18:34 volumio volumio[1941]: error: Failed to stat '/mnt/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)'
Dec 13 12:18:34 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:18:34 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::updateTrackBlock
Dec 13 12:18:34 volumio volumio[1941]: info: CorePlayQueue::getTrackBlock
Dec 13 12:18:34 volumio volumio[1941]: info: CoreCommandRouter::volumioPlay
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::play index 0
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::play index undefined
Dec 13 12:18:34 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:34 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:38 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:18:38 volumio volumio[1941]: info: CURURI: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:18:38 volumio volumio[1941]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
Dec 13 12:18:38 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:18:39 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:18:39 volumio volumio[1941]: info: CURURI: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:18:39 volumio volumio[1941]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
Dec 13 12:18:40 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:18:40 volumio volumio[1941]: info: CURURI: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:18:40 volumio volumio[1941]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
Dec 13 12:18:43 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:18:43 volumio volumio[1941]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::ClearQueue
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:43 volumio volumio[1941]: info: CorePlayQueue::clearPlayQueue
Dec 13 12:18:43 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:18:43 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::addQueueItems
Dec 13 12:18:43 volumio volumio[1941]: info: CorePlayQueue::addQueueItems
Dec 13 12:18:43 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:18:43 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:18:43 volumio volumio[1941]: info: Using cached record of: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:18:43 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:18:43 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::updateTrackBlock
Dec 13 12:18:43 volumio volumio[1941]: info: CorePlayQueue::getTrackBlock
Dec 13 12:18:43 volumio volumio[1941]: info: CoreCommandRouter::volumioPlay
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::play index 0
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::play index undefined
Dec 13 12:18:43 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:18:43 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:46 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:18:52 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:18:52 volumio volumio[1941]: info: CURURI: music-library
Dec 13 12:18:52 volumio volumio[1941]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
Dec 13 12:18:54 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:18:54 volumio volumio[1941]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
Dec 13 12:18:54 volumio volumio[1941]: info: Preparing to generate the ALSA configuration file
Dec 13 12:18:54 volumio volumio[1941]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Dec 13 12:18:54 volumio volumio[1941]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Dec 13 12:18:54 volumio volumio[1941]: info: Reading ALSA contributions from plugins.
Dec 13 12:18:54 volumio volumio[1941]: info: Asound.conf file unchanged, so no further update is needed
Dec 13 12:18:54 volumio volumio[1941]: info: Output device has changed, restarting MPD
Dec 13 12:18:55 volumio sudo[2082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 13 12:18:55 volumio volumio[1941]: info: Output device has changed, restarting Shairport Sync
Dec 13 12:18:55 volumio sudo[2082]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:18:55 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:55 volumio sudo[2084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 13 12:18:55 volumio sudo[2084]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:55 volumio systemd[1]: Stopping Music Player Daemon...
Dec 13 12:18:55 volumio volumio[1941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 13 12:18:55 volumio volumio[1941]: info: ___________ START PLUGINS ___________
Dec 13 12:18:55 volumio volumio[1941]: info: ControllerMpd::onStart: Initializing MPD
Dec 13 12:18:55 volumio volumio[1941]: info: Creating MPD Configuration file
Dec 13 12:18:55 volumio sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 13 12:18:55 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:55 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 13 12:18:55 volumio volumio[1941]: info: [1765624735148] CoreMusicLibrary::Adding element Media Servers
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 13 12:18:55 volumio sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 13 12:18:55 volumio sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 13 12:18:55 volumio volumio[1941]: info: [1765624735303] CoreMusicLibrary::Adding element Last_100
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 13 12:18:55 volumio volumio[1941]: info: [1765624735307] CoreMusicLibrary::Adding element Webradio
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 13 12:18:55 volumio volumio[1941]: info: AutoStart - onStart
Dec 13 12:18:55 volumio volumio[1941]: info: Loading i18n strings for locale ua
Dec 13 12:18:55 volumio volumio[1941]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Dec 13 12:18:55 volumio systemd[1]: mpd.service: Succeeded.
Dec 13 12:18:55 volumio systemd[1]: Stopped Music Player Daemon.
Dec 13 12:18:55 volumio volumio[1941]: info: /tmp/myfifo created
Dec 13 12:18:55 volumio sudo[2106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1
Dec 13 12:18:55 volumio systemd[1]: Starting Music Player Daemon...
Dec 13 12:18:55 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:55 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:55 volumio volumio[1941]: info: snd-dummy loaded
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:18:55 volumio volumio[1941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 13 12:18:55 volumio volumio[1941]: info: Loading i18n strings for locale ua
Dec 13 12:18:55 volumio volumio[1941]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 13 12:18:55 volumio volumio[1941]: info: Volumio Calling Home
Dec 13 12:18:55 volumio sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service
Dec 13 12:18:55 volumio sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:55 volumio sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service
Dec 13 12:18:55 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:55 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Dec 13 12:18:55 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:55 volumio systemd[1]: Reloading.
Dec 13 12:18:55 volumio volumio[1941]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/'
Dec 13 12:18:55 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:55 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:55 volumio volumio[1941]: info: MPD Permissions set
Dec 13 12:18:55 volumio volumio[1941]: info: MPD Permissions set
Dec 13 12:18:56 volumio volumio[1941]: info: Volumio called home
Dec 13 12:18:56 volumio volumio[1941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:18:56 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Dec 13 12:18:56 volumio volumio[1941]: info: touch_display: No Raspberry Pi Foundation touch screen detected.
Dec 13 12:18:56 volumio sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf
Dec 13 12:18:56 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:56 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:56 volumio volumio[1941]: info: Starting Shairport Sync
Dec 13 12:18:56 volumio volumio[1941]: info: Starting Shairport Sync
Dec 13 12:18:56 volumio volumio[1941]: info: Starting Shairport Sync
Dec 13 12:18:56 volumio sudo[2148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 13 12:18:56 volumio sudo[2148]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:56 volumio volumio[1941]: info: Starting Shairport Sync
Dec 13 12:18:56 volumio sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 13 12:18:56 volumio sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 13 12:18:56 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:18:56 volumio volumio[1941]: info: CURURI: music-library
Dec 13 12:18:56 volumio volumio[1941]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
Dec 13 12:18:56 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:56 volumio volumio[1941]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set.
Dec 13 12:18:56 volumio sudo[2157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 13 12:18:56 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:56 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:56 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:57 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:57 volumio volumio[1941]: info: touch_display: systemctl daemon-reload succeeded.
Dec 13 12:18:57 volumio systemd[1]: Reloading.
Dec 13 12:18:57 volumio sudo[2115]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:57 volumio volumio[1941]: info: touch_display: systemctl stop getty@tty1.service succeeded.
Dec 13 12:18:57 volumio sudo[2160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Dec 13 12:18:57 volumio sudo[2160]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:18:57 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Dec 13 12:18:57 volumio mpd[2102]: Dec 13 12:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 13 12:18:58 volumio systemd[1]: Started Music Player Daemon.
Dec 13 12:18:58 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:58 volumio sudo[2084]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:58 volumio sudo[2094]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:58 volumio volumio[1941]: info: touch_display: systemctl disable getty@tty1.service succeeded.
Dec 13 12:18:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 13 12:18:58 volumio systemd[1]: shairport-sync.service: Succeeded.
Dec 13 12:18:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 13 12:18:58 volumio sudo[2160]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:58 volumio volumio[1941]: error: MPD error: The expression evaluated to a falsy value:
Dec 13 12:18:58 volumio volumio[1941]: assert.ok(self.idling)
Dec 13 12:18:58 volumio volumio[1941]: error: The expression evaluated to a falsy value:
Dec 13 12:18:58 volumio volumio[1941]: assert.ok(self.idling)
Dec 13 12:18:58 volumio volumio[1941]: error: updateQueue error: null
Dec 13 12:18:58 volumio volumio[1941]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Dec 13 12:18:58 volumio volumio[1941]: info: touch_display: Volumio Kiosk started.
Dec 13 12:18:58 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:58 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:58 volumio volumio[1941]: info: Completed starting Core Plugins
Dec 13 12:18:58 volumio volumio[1941]: info: -------------------------------------------
Dec 13 12:18:58 volumio volumio[1941]: info: ----- MyVolumio plugins startup ----
Dec 13 12:18:58 volumio volumio[1941]: info: -------------------------------------------
Dec 13 12:18:58 volumio volumio[1941]: info: [MyVolumio PluginManager] Fetching plans data....
Dec 13 12:18:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 13 12:18:58 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:58 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:58 volumio volumio[1941]: info: MPD running with PID2102
Dec 13 12:18:58 volumio volumio[1941]: ,establishing connection
Dec 13 12:18:58 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:58 volumio sudo[2148]: pam_unix(sudo:session): session closed for user root
Dec 13 12:18:58 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:58 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:58 volumio volumio[1941]: info: Shairport-Sync Started
Dec 13 12:18:58 volumio volumio[1941]: Error adding Membership: Error: addMembership EINVAL
Dec 13 12:18:58 volumio volumio[1941]: info: Shairport-Sync Started
Dec 13 12:18:58 volumio volumio[1941]: info: Shairport-Sync Started
Dec 13 12:18:58 volumio volumio[1941]: info: Shairport-Sync Started
Dec 13 12:18:58 volumio volumio[1941]: error: updateQueue error: null
Dec 13 12:18:58 volumio volumio[1941]: info: touch_display: X display number found: 0
Dec 13 12:18:58 volumio volumio[1941]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Dec 13 12:18:58 volumio volumio[1941]: info: touch_display: X display number found: 0
Dec 13 12:18:58 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:18:58 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:18:58 volumio volumio[1941]: info: touch_display: Setting screensaver timeout to 120 seconds.
Dec 13 12:19:01 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:19:06 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:19:06 volumio volumio[1941]: info: CURURI: music-library
Dec 13 12:19:06 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 13 12:19:06 volumio volumio[1941]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 13 12:19:06 volumio volumio[1941]: info: Adding plugin multiroom to MyMusic Plugins
Dec 13 12:19:06 volumio volumio[1941]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 13 12:19:06 volumio volumio[1941]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 13 12:19:06 volumio volumio[1941]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 13 12:19:06 volumio volumio[1941]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 13 12:19:06 volumio volumio[1941]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 13 12:19:08 volumio volumio[1941]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 13 12:19:08 volumio volumio[1941]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 13 12:19:08 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:08 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:08 volumio volumio[1941]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 13 12:19:08 volumio volumio[1941]: info: MyVolumio login type: Token
Dec 13 12:19:08 volumio volumio[1941]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 13 12:19:08 volumio volumio[1941]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 13 12:19:09 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:19:09 volumio volumio[1941]: info: Starting Streaming Service Transparent Proxy
Dec 13 12:19:09 volumio volumio[1941]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 13 12:19:09 volumio volumio[1941]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 13 12:19:09 volumio volumio[1941]: info: Streaming services startup
Dec 13 12:19:09 volumio volumio[1941]: info: Starting Streaming Daemon
Dec 13 12:19:09 volumio sudo[2240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 13 12:19:09 volumio sudo[2240]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:19:09 volumio sudo[2240]: pam_unix(sudo:session): session closed for user root
Dec 13 12:19:09 volumio volumio[1941]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 13 12:19:09 volumio volumio[1941]: error: Cannot start Volumio Streaming Daemon
Dec 13 12:19:09 volumio volumio[1941]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 13 12:19:09 volumio volumio[1941]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 13 12:19:09 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:19:09 volumio volumio[1941]: info: CURURI: music-library/USB
Dec 13 12:19:09 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:10 volumio volumio[1941]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 13 12:19:10 volumio volumio[1941]: info: MyVolumio token set successfully
Dec 13 12:19:10 volumio volumio[1941]: info: MYVOLUMIO: Adding device
Dec 13 12:19:10 volumio volumio[1941]: info: MYVOLUMIO: Evaluating Server
Dec 13 12:19:10 volumio volumio[1941]: STREAMING PROXY: Starting server on port 3245
Dec 13 12:19:10 volumio volumio[1941]: Node JS runtime: 14
Dec 13 12:19:11 volumio volumio[1941]: info: MyVolumio status changed
Dec 13 12:19:11 volumio volumio[1941]: info: Streaming services startup
Dec 13 12:19:11 volumio volumio[1941]: info: Starting Streaming Daemon
Dec 13 12:19:11 volumio volumio[1941]: info: Removing browser output: myVolumio user plan is not superstar
Dec 13 12:19:11 volumio volumio[1941]: info: Removing audio output:
Dec 13 12:19:11 volumio volumio[1941]: info: Stoppping Tunnel 1
Dec 13 12:19:11 volumio sudo[2268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 13 12:19:11 volumio sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:19:11 volumio sudo[2271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Dec 13 12:19:11 volumio sudo[2268]: pam_unix(sudo:session): session closed for user root
Dec 13 12:19:11 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 13 12:19:11 volumio volumio[1941]: error: Cannot start Volumio Streaming Daemon
Dec 13 12:19:11 volumio volumio[1941]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 13 12:19:11 volumio volumio[1941]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 13 12:19:11 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root
Dec 13 12:19:11 volumio volumio[1941]: info: Remote SSH Stopped
Dec 13 12:19:11 volumio volumio[1941]: info: Setting Geolocation for MyVolumio to eu11
Dec 13 12:19:11 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:11 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:11 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:11 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:19:11 volumio volumio[1941]: info: CURURI: music-library/USB/06D7-9332
Dec 13 12:19:12 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:12 volumio volumio[1941]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 13 12:19:12 volumio volumio[1941]: info: Updating MyVolumio device info
Dec 13 12:19:12 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:12 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:12 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:12 volumio volumio[1941]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 13 12:19:14 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:19:14 volumio volumio[1941]: info: CURURI: music-library/USB/06D7-9332/JAZZ
Dec 13 12:19:15 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:15 volumio volumio[1941]: info: Preloading song: music-library/USB/06D7-9332/JAZZ/Alina Zalozna Trio Live at 32 Jazz Club_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Preloading song: music-library/USB/06D7-9332/JAZZ/Jazz Bez Тернопіль 2014 день 3 частина 2. FUTUREthno_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Preloading song: music-library/USB/06D7-9332/JAZZ/Julia Perminova Final Exam Jazz Institute Berlin (2021)_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Preloading song: music-library/USB/06D7-9332/JAZZ/Mozart, Beethoven, Chopin, Paganini, Pushkin farewell, Rousseau - Masters of_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Preloading song: music-library/USB/06D7-9332/JAZZ/TOP 10 PIANO COVERS - Peter Bence_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Preloading song: music-library/USB/06D7-9332/JAZZ/TOP 10 Songs - 2020 Rewind - Peter Bence_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/Alina Zalozna Trio Live at 32 Jazz Club_256k.mp3 in service mpd
Dec 13 12:19:15 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=/Alina%20Zalozna%20Trio%20Live%20at%2032%20Jazz%20Club/extralarge&path=%2Fmnt%2FUSB%2F06D7-9332%2FJAZZ%2FAlina%20Zalozna%20Trio%20Live%20at%2032%20Jazz%20Club_256k.mp3&metadata=false
Dec 13 12:19:15 volumio volumio[1941]: info: URI /mnt/USB/06D7-9332/JAZZ/Alina Zalozna Trio Live at 32 Jazz Club_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/Jazz Bez Тернопіль 2014 день 3 частина 2. FUTUREthno_256k.mp3 in service mpd
Dec 13 12:19:15 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=/Jazz%20Bez%20%D0%A2%D0%B5%D1%80%D0%BD%D0%BE%D0%BF%D1%96%D0%BB%D1%8C%202014%20%D0%B4%D0%B5%D0%BD%D1%8C%203%20%D1%87%D0%B0%D1%81%D1%82%D0%B8%D0%BD%D0%B0%202.%20FUTUREthno/extralarge&path=%2Fmnt%2FUSB%2F06D7-9332%2FJAZZ%2FJazz%20Bez%20%D0%A2%D0%B5%D1%80%D0%BD%D0%BE%D0%BF%D1%96%D0%BB%D1%8C%202014%20%D0%B4%D0%B5%D0%BD%D1%8C%203%20%D1%87%D0%B0%D1%81%D1%82%D0%B8%D0%BD%D0%B0%202.%20FUTUREthno_256k.mp3&metadata=false
Dec 13 12:19:15 volumio volumio[1941]: info: URI /mnt/USB/06D7-9332/JAZZ/Jazz Bez Тернопіль 2014 день 3 частина 2. FUTUREthno_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/Julia Perminova Final Exam Jazz Institute Berlin (2021)_256k.mp3 in service mpd
Dec 13 12:19:15 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=/Julia%20Perminova%20Final%20Exam%20Jazz%20Institute%20Berlin%20(2021)/extralarge&path=%2Fmnt%2FUSB%2F06D7-9332%2FJAZZ%2FJulia%20Perminova%20Final%20Exam%20Jazz%20Institute%20Berlin%20(2021)_256k.mp3&metadata=false
Dec 13 12:19:15 volumio volumio[1941]: info: URI /mnt/USB/06D7-9332/JAZZ/Julia Perminova Final Exam Jazz Institute Berlin (2021)_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/Mozart, Beethoven, Chopin, Paganini, Pushkin farewell, Rousseau - Masters of_256k.mp3 in service mpd
Dec 13 12:19:15 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Mozart%2C%20Beethoven%2C%20Chopin%2C%20Paganini%2C%20Pushkin%20farewell%2C%20Rousseau/Mozart%2C%20Beethoven%2C%20Chopin%2C%20Paganini%2C%20Pushkin%20farewell%2C%20Rousseau/extralarge&path=%2Fmnt%2FUSB%2F06D7-9332%2FJAZZ%2FMozart%2C%20Beethoven%2C%20Chopin%2C%20Paganini%2C%20Pushkin%20farewell%2C%20Rousseau%20-%20Masters%20of_256k.mp3&metadata=false
Dec 13 12:19:15 volumio volumio[1941]: info: URI /mnt/USB/06D7-9332/JAZZ/Mozart, Beethoven, Chopin, Paganini, Pushkin farewell, Rousseau - Masters of_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/TOP 10 PIANO COVERS - Peter Bence_256k.mp3 in service mpd
Dec 13 12:19:15 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=/TOP%2010%20PIANO%20COVERS%20%2F%20Peter%20Bence/extralarge&path=%2Fmnt%2FUSB%2F06D7-9332%2FJAZZ%2FTOP%2010%20PIANO%20COVERS%20-%20Peter%20Bence_256k.mp3&metadata=false
Dec 13 12:19:15 volumio volumio[1941]: info: URI /mnt/USB/06D7-9332/JAZZ/TOP 10 PIANO COVERS - Peter Bence_256k.mp3
Dec 13 12:19:15 volumio volumio[1941]: info: AutoStart - Plugin is starting
Dec 13 12:19:15 volumio volumio[1941]: info: CoreCommandRouter::volumioGetQueue
Dec 13 12:19:15 volumio volumio[1941]: info: CoreStateMachine::getQueue
Dec 13 12:19:15 volumio volumio[1941]: info: CorePlayQueue::getQueue
Dec 13 12:19:15 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/TOP 10 Songs - 2020 Rewind - Peter Bence_256k.mp3 in service mpd
Dec 13 12:19:15 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=/TOP%2010%20Songs%20%2F%202020%20Rewind%20%2F%20Peter%20Bence/extralarge&path=%2Fmnt%2FUSB%2F06D7-9332%2FJAZZ%2FTOP%2010%20Songs%20-%202020%20Rewind%20-%20Peter%20Bence_256k.mp3&metadata=false
Dec 13 12:19:15 volumio volumio[1941]: info: URI /mnt/USB/06D7-9332/JAZZ/TOP 10 Songs - 2020 Rewind - Peter Bence_256k.mp3
Dec 13 12:19:16 volumio volumio[1941]: info: MYVOLUMIO: Adding device
Dec 13 12:19:16 volumio volumio[1941]: info: MYVOLUMIO: Evaluating Server
Dec 13 12:19:16 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:19:16 volumio volumio[1941]: info: Setting Geolocation for MyVolumio to eu7
Dec 13 12:19:16 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:16 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:16 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:17 volumio volumio[1941]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 13 12:19:17 volumio volumio[1941]: info: Updating MyVolumio device info
Dec 13 12:19:17 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:17 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:17 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:18 volumio volumio[1941]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 13 12:19:21 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:19:21 volumio volumio[1941]: info: CURURI: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино
Dec 13 12:19:21 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:21 volumio volumio[1941]: info: Preloading song: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac
Dec 13 12:19:21 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac in service mpd
Dec 13 12:19:21 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=//extralarge&path=%2Fmnt%2FUSB%2F06D7-9332%2FJAZZ%2F%5BLP%5D%20%D0%90%D0%BD%D0%B4%D1%80%D0%B5%D0%B9%20%D0%9F%D0%B5%D1%82%D1%80%D0%BE%D0%B2%20-%20%D0%9B%D1%8E%D0%B1%D0%BE%D0%B2%D1%8C%20%D0%BC%D0%BE%D1%8F-%D0%BA%D0%B8%D0%BD%D0%BE%2F%5BLP%5D%20%D0%90%D0%BD%D0%B4%D1%80%D0%B5%D0%B9%20%D0%9F%D0%B5%D1%82%D1%80%D0%BE%D0%B2%20-%20%D0%9B%D1%8E%D0%B1%D0%BE%D0%B2%D1%8C%20%D0%BC%D0%BE%D1%8F-%D0%BA%D0%B8%D0%BD%D0%BE.flac&metadata=false
Dec 13 12:19:21 volumio volumio[1941]: info: URI /mnt/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac
Dec 13 12:19:23 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 13 12:19:23 volumio volumio[1941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11
Dec 13 12:19:23 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:19:23 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:24 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:19:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 13 12:19:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 13 12:19:25 volumio volumio-remote-updater[522]: No test mode
Dec 13 12:19:25 volumio volumio-remote-updater[522]: No alpha test mode
Dec 13 12:19:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 13 12:19:25 volumio volumio[1941]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for TIDAL Authentication refresh
\n
\nNEW ADDITIONS
\n\n- Update Bluez to 5.83
\n
\n","title":"Update v3.874","updateavailable":true}
Dec 13 12:19:25 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 13 12:19:25 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:25 volumio volumio[1941]: info:
Dec 13 12:19:25 volumio volumio[1941]: [1765624765998] ---------------------------- Client requests add and Play Volumio CUE entry
Dec 13 12:19:26 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue
Dec 13 12:19:26 volumio volumio[1941]: info: Adding CUE individual entry: 0 USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:26 volumio volumio[1941]: info: CoreCommandRouter::volumioAddQueueItems
Dec 13 12:19:26 volumio volumio[1941]: info: CoreStateMachine::addQueueItems
Dec 13 12:19:26 volumio volumio[1941]: info: CorePlayQueue::addQueueItems
Dec 13 12:19:26 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:26 volumio volumio[1941]: info: Adding Item to queue: cue://USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue@0
Dec 13 12:19:26 volumio volumio[1941]: info: Exploding uri cue://USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue@0 in service mpd
Dec 13 12:19:26 volumio volumio[1941]: info: CoreCommandRouter::volumioPlay
Dec 13 12:19:26 volumio volumio[1941]: info: CoreStateMachine::play index 0
Dec 13 12:19:26 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:26 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:19:26 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:26 volumio volumio[1941]: info: CoreStateMachine::play index undefined
Dec 13 12:19:26 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:26 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:26 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:19:26 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:19:26 volumio volumio[1941]: info: CoreStateMachine::updateTrackBlock
Dec 13 12:19:26 volumio volumio[1941]: info: CorePlayQueue::getTrackBlock
Dec 13 12:19:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:19:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:19:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 13 12:19:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 13 12:19:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 13 12:19:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 13 12:19:28 volumio volumio[1941]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 13 12:19:28 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 13 12:19:29 volumio volumio[1941]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 13 12:19:29 volumio volumio[1941]: info: Completed starting MyVolumio Plugin
Dec 13 12:19:29 volumio volumio[1941]: info: BOOT COMPLETED
Dec 13 12:19:29 volumio volumio[1941]: [Metrics] CommandRouter: 70s 270.86ms
Dec 13 12:19:29 volumio volumio[1941]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 13 12:19:29 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 13 12:19:29 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 13 12:19:29 volumio volumio[1941]: info: CoreCommandRouter::Close All Modals sent
Dec 13 12:19:29 volumio volumio[1941]: info: CoreCommandRouter::Close All Modals sent
Dec 13 12:19:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 13 12:19:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 13 12:19:30 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 13 12:19:31 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:19:36 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:36 volumio volumio[1941]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 13 12:19:36 volumio volumio[1941]: info: CoreStateMachine::ClearQueue
Dec 13 12:19:36 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:19:36 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:36 volumio volumio[1941]: info: CorePlayQueue::clearPlayQueue
Dec 13 12:19:36 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:19:36 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:19:36 volumio volumio[1941]: info: CoreStateMachine::addQueueItems
Dec 13 12:19:36 volumio volumio[1941]: info: CorePlayQueue::addQueueItems
Dec 13 12:19:36 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac
Dec 13 12:19:36 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:36 volumio volumio[1941]: info: Exploding uri music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue in service mpd
Dec 13 12:19:36 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::updateTrackBlock
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrackBlock
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPlay
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::play index 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::play index undefined
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::startPlaybackTimer
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::clearAddPlayTracks USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand stop
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand stop took 13 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand clear
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:37 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand clear took 9 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand add "USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac"
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:37 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:37 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:37 volumio volumio[1941]: error: updateQueue error: null
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:37 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 15ms
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand add "USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac" took 11 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 9ms
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 8ms
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand play
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:37 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:37 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 34ms
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand play took 30 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 29ms
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 25ms
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand status took 420 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand status took 424 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand status took 420 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:37 volumio volumio[1941]: info:
Dec 13 12:19:37 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 20 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand status took 17 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 12 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 12 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand status took 8 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand status took 6 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:37 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus stop
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":115,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2197 Kbps","isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:37 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:37 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":178,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2174 Kbps","isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:37 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:37 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 498ms
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 548ms
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 549ms
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 108 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 106 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 106 milliseconds
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:37 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":503,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2200 Kbps","isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:37 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:37 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":677,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2196 Kbps","isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:37 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:37 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":677,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2196 Kbps","isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:37 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:37 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:37 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:37 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 319ms
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 311ms
Dec 13 12:19:37 volumio volumio[1941]: info: ------------------------------ 309ms
Dec 13 12:19:38 volumio volumio[1941]: info: touch_display: Setting screensaver timeout to 0 seconds.
Dec 13 12:19:38 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::ClearQueue
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::stPlaybackTimer
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::updateTrackBlock
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrackBlock
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::serviceStop
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::serviceStop
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::stop
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand stop
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::clearPlayQueue
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::addQueueItems
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::addQueueItems
Dec 13 12:19:38 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: Using cached record of: music-library/USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.cue
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::updateTrackBlock
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrackBlock
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPlay
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::play index 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::play index undefined
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::startPlaybackTimer
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::clearAddPlayTracks USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand stop
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand stop took 150 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand stop took 37 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand clear
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:38 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 78 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand clear took 78 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 76 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 73 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand add "USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac"
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 13 12:19:38 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::syncState stateService stop
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus stop
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:38 volumio volumio[1941]: info: No code
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 13 12:19:38 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::syncState stateService stop
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus stop
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:38 volumio volumio[1941]: info: No code
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 263ms
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 263ms
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:38 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:38 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:38 volumio volumio[1941]: error: updateQueue error: null
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:38 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 211ms
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 202 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand add "USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac" took 202 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 18ms
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 16ms
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand play
Dec 13 12:19:38 volumio volumio[1941]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 299ms
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:38 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces system playlist update
Dec 13 12:19:38 volumio volumio[1941]: info: Ignoring MPD Status Update
Dec 13 12:19:38 volumio volumio[1941]: info: touch_display: Setting screensaver timeout to 120 seconds.
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 63ms
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand play took 53 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 52ms
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 49ms
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 311 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 313 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 310 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 6 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:38 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus stop
Dec 13 12:19:38 volumio volumio[1941]: info: ------------------------------ 334ms
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info:
Dec 13 12:19:38 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::getState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 23 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 18 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 17 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 11 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: info: sendMpdCommand status took 7 milliseconds
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::parseState
Dec 13 12:19:38 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 13 12:19:38 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:38 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:38 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:38 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:38 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:38 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:39 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:39 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio volumio[1941]: info: ------------------------------ 457ms
Dec 13 12:19:39 volumio volumio[1941]: info: ------------------------------ 456ms
Dec 13 12:19:39 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 120 milliseconds
Dec 13 12:19:39 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 118 milliseconds
Dec 13 12:19:39 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 117 milliseconds
Dec 13 12:19:39 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:39 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:39 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo
Dec 13 12:19:39 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2199 Kbps","isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:39 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:39 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":587,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2206 Kbps","isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:39 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:39 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio volumio[1941]: info: ControllerMpd::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::servicePushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":677,"duration":2390,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2196 Kbps","isStreaming":false,"title":"[LP] Андрей Петров - Любовь моя-кино.flac","artist":null,"album":null,"uri":"USB/06D7-9332/JAZZ/[LP] Андрей Петров - Любовь моя-кино/[LP] Андрей Петров - Любовь моя-кино.flac","trackType":"flac"}
Dec 13 12:19:39 volumio volumio[1941]: verbose: CURRENT POSITION 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::syncState stateService play
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play
Dec 13 12:19:39 volumio volumio[1941]: info: Received an update from plugin. extracting info from payload
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:39 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:39 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:39 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:19:39 volumio volumio[1941]: info: ------------------------------ 356ms
Dec 13 12:19:39 volumio volumio[1941]: info: ------------------------------ 340ms
Dec 13 12:19:39 volumio volumio[1941]: info: ------------------------------ 339ms
Dec 13 12:19:39 volumio volumio[1941]: info: touch_display: Setting screensaver timeout to 0 seconds.
Dec 13 12:19:46 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:19:50 volumio volumio[1941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12
Dec 13 12:19:50 volumio volumio[1941]: info: CoreCommandRouter::volumioGetState
Dec 13 12:19:50 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:54 volumio wpa_supplicant[811]: wlan0: Failed to initiate sched scan
Dec 13 12:19:55 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:19:55 volumio volumio[1941]: info: CURURI: music-library/USB/6745-2301
Dec 13 12:19:55 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:56 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 13 12:19:56 volumio volumio[1941]: info: CURURI: music-library/USB/6745-2301/JAZZ
Dec 13 12:19:56 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:56 volumio volumio[1941]: info: Preloading song: music-library/USB/6745-2301/JAZZ/KRUTЬ - Літепло _ Full album_256k.mp3
Dec 13 12:19:56 volumio volumio[1941]: info: Preloading song: music-library/USB/6745-2301/JAZZ/KRUTЬ - Плейлист №1_256k.mp3
Dec 13 12:19:56 volumio volumio[1941]: info: Preloading song: music-library/USB/6745-2301/JAZZ/Марія Чайковська - Ukrainian songs (vol.1)_256k.mp3
Dec 13 12:19:56 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/KRUTЬ - Літепло _ Full album_256k.mp3 in service mpd
Dec 13 12:19:56 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Full%20album/Full%20album/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FKRUT%D0%AC%20-%20%20%D0%9B%D1%96%D1%82%D0%B5%D0%BF%D0%BB%D0%BE%20_%20Full%20album_256k.mp3&metadata=false
Dec 13 12:19:56 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/KRUTЬ - Літепло _ Full album_256k.mp3
Dec 13 12:19:56 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/KRUTЬ - Плейлист №1_256k.mp3 in service mpd
Dec 13 12:19:56 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=KRUT%D0%AC/KRUT%D0%AC/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FKRUT%D0%AC%20-%20%D0%9F%D0%BB%D0%B5%D0%B9%D0%BB%D0%B8%D1%81%D1%82%20%E2%84%961_256k.mp3&metadata=false
Dec 13 12:19:56 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/KRUTЬ - Плейлист №1_256k.mp3
Dec 13 12:19:56 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/Марія Чайковська - Ukrainian songs (vol.1)_256k.mp3 in service mpd
Dec 13 12:19:56 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=%D0%9C%D0%B0%D1%80%D1%96%D1%8F%20%D0%A7%D0%B0%D0%B9%D0%BA%D0%BE%D0%B2%D1%81%D1%8C%D0%BA%D0%B0/%D0%9C%D0%B0%D1%80%D1%96%D1%8F%20%D0%A7%D0%B0%D0%B9%D0%BA%D0%BE%D0%B2%D1%81%D1%8C%D0%BA%D0%B0/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2F%D0%9C%D0%B0%D1%80%D1%96%D1%8F%20%D0%A7%D0%B0%D0%B9%D0%BA%D0%BE%D0%B2%D1%81%D1%8C%D0%BA%D0%B0%20-%20Ukrainian%20songs%20(vol.1)_256k.mp3&metadata=false
Dec 13 12:19:56 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Марія Чайковська - Ukrainian songs (vol.1)_256k.mp3
Dec 13 12:19:58 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:58 volumio volumio[1941]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 13 12:19:58 volumio volumio[1941]: info: CoreStateMachine::ClearQueue
Dec 13 12:19:58 volumio volumio[1941]: info: CoreStateMachine::stop
Dec 13 12:19:58 volumio volumio[1941]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 13 12:19:58 volumio volumio[1941]: info: CoreStateMachine::stPlaybackTimer
Dec 13 12:19:58 volumio volumio[1941]: info: CoreStateMachine::updateTrackBlock
Dec 13 12:19:58 volumio volumio[1941]: info: CorePlayQueue::getTrackBlock
Dec 13 12:19:58 volumio volumio[1941]: info: CoreStateMachine::pushState
Dec 13 12:19:58 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:58 volumio volumio[1941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 12:19:58 volumio volumio[1941]: info: CoreCommandRouter::volumioPushState
Dec 13 12:19:58 volumio volumio[1941]: info: CoreStateMachine::serviceStop
Dec 13 12:19:58 volumio volumio[1941]: info: CorePlayQueue::getTrack 0
Dec 13 12:19:58 volumio volumio[1941]: info: CoreCommandRouter::serviceStop
Dec 13 12:19:58 volumio volumio[1941]: info: ControllerMpd::stop
Dec 13 12:19:58 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand stop
Dec 13 12:19:58 volumio volumio[1941]: info: CorePlayQueue::clearPlayQueue
Dec 13 12:19:58 volumio volumio[1941]: info: CorePlayQueue::saveQueue
Dec 13 12:19:58 volumio volumio[1941]: info: CoreCommandRouter::volumioPushQueue
Dec 13 12:19:58 volumio volumio[1941]: info: CoreStateMachine::addQueueItems
Dec 13 12:19:58 volumio volumio[1941]: info: CorePlayQueue::addQueueItems
Dec 13 12:19:58 volumio volumio[1941]: info: Preload queue cleared
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/KRUTЬ - Літепло _ Full album_256k.mp3
Dec 13 12:19:58 volumio volumio[1941]: info: Using cached record of: music-library/USB/6745-2301/JAZZ/KRUTЬ - Літепло _ Full album_256k.mp3
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/KRUTЬ - Плейлист №1_256k.mp3
Dec 13 12:19:58 volumio volumio[1941]: info: Using cached record of: music-library/USB/6745-2301/JAZZ/KRUTЬ - Плейлист №1_256k.mp3
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Марія Чайковська - Ukrainian songs (vol.1)_256k.mp3
Dec 13 12:19:58 volumio volumio[1941]: info: Using cached record of: music-library/USB/6745-2301/JAZZ/Марія Чайковська - Ukrainian songs (vol.1)_256k.mp3
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)
Dec 13 12:19:58 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid) in service mpd
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:19:58 volumio volumio[1941]: info: Using cached record of: music-library/USB/6745-2301/JAZZ/Mike Longo - Funkia 2003 (1974)
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Ron Carter - Cocktails at the Cotton Club (2013, Somethin' Else-Japan)
Dec 13 12:19:58 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/Ron Carter - Cocktails at the Cotton Club (2013, Somethin' Else-Japan) in service mpd
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Ron Carter & Richard Galliano - An Evening With (2017, In+Out)
Dec 13 12:19:58 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/Ron Carter & Richard Galliano - An Evening With (2017, In+Out) in service mpd
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Ron Carter & Richard Galliano - An Evening With (2017) [FLAC]
Dec 13 12:19:58 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/Ron Carter & Richard Galliano - An Evening With (2017) [FLAC] in service mpd
Dec 13 12:19:58 volumio volumio[1941]: info: Adding Item to queue: music-library/USB/6745-2301/JAZZ/Наталья Могилевская - Любов - то велика сила (2020)
Dec 13 12:19:58 volumio volumio[1941]: info: Exploding uri music-library/USB/6745-2301/JAZZ/Наталья Могилевская - Любов - то велика сила (2020) in service mpd
Dec 13 12:19:58 volumio volumio[1941]: info:
Dec 13 12:19:58 volumio volumio[1941]: ---------------------------- MPD announces state update: player
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Leroy%20Vinnegar/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F01%20-%20Leroy%20Vinnegar%20-%20Walk%20On.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/01 - Leroy Vinnegar - Walk On.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Leroy%20Vinnegar/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F02%20-%20Leroy%20Vinnegar%20-%20Would%20You%20Like%20to%20Take%20a%20Walk.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/02 - Leroy Vinnegar - Would You Like to Take a Walk.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Leroy%20Vinnegar/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F03%20-%20Leroy%20Vinnegar%20-%20On%20the%20Sunny%20Side%20of%20the%20Street.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/03 - Leroy Vinnegar - On the Sunny Side of the Street.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Leroy%20Vinnegar/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F04%20-%20Leroy%20Vinnegar%20-%20Walkin'.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/04 - Leroy Vinnegar - Walkin'.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Leroy%20Vinnegar/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F05%20-%20Leroy%20Vinnegar%20-%20Walkin'%20My%20Baby%20Back%20Home.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/05 - Leroy Vinnegar - Walkin' My Baby Back Home.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Leroy%20Vinnegar/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F06%20-%20Leroy%20Vinnegar%20-%20I'll%20Walk%20Alone.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/06 - Leroy Vinnegar - I'll Walk Alone.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Leroy%20Vinnegar/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F07%20-%20Leroy%20Vinnegar%20-%20Walkin'%20by%20the%20River.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/07 - Leroy Vinnegar - Walkin' by the River.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Doug%20Watkins/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F08%20-%20Doug%20Watkins%20-%20One%20Guy.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/08 - Doug Watkins - One Guy.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Doug%20Watkins/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F09%20-%20Doug%20Watkins%20-%20Confessin'.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/09 - Doug Watkins - Confessin'.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Doug%20Watkins/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F10%20-%20Doug%20Watkins%20-%20Soulnik.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/10 - Doug Watkins - Soulnik.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Doug%20Watkins/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F11%20-%20Doug%20Watkins%20-%20Andre's%20Bag.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/11 - Doug Watkins - Andre's Bag.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Doug%20Watkins/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F12%20-%20Doug%20Watkins%20-%20I%20Remember%20You.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/12 - Doug Watkins - I Remember You.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Doug%20Watkins/Four%20Classic%20Albums%20CD1/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD1%2F13%20-%20Doug%20Watkins%20-%20Imagination.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD1/13 - Doug Watkins - Imagination.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Paul%20Chambers/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F01%20-%20Paul%20Chambers%20-%20Melody.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/01 - Paul Chambers - Melody.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Paul%20Chambers/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F02%20-%20Paul%20Chambers%20-%20Bass%20Region.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/02 - Paul Chambers - Bass Region.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Paul%20Chambers/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F03%20-%20Paul%20Chambers%20-%20Retrogress.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/03 - Paul Chambers - Retrogress.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Paul%20Chambers/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F04%20-%20Paul%20Chambers%20-%20Mopp%20Shoe%20Blues.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/04 - Paul Chambers - Mopp Shoe Blues.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Paul%20Chambers/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F05%20-%20Paul%20Chambers%20-%20Blessed.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/05 - Paul Chambers - Blessed.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F06%20-%20Ron%20Carter%20-%20Rally.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/06 - Ron Carter - Rally.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F07%20-%20Ron%20Carter%20-%20Bass%20Duet.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/07 - Ron Carter - Bass Duet.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F08%20-%20Ron%20Carter%20-%20Softly%20as%20in%20a%20Morning%20Sunrise.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/08 - Ron Carter - Softly as in a Morning Sunrise.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F09%20-%20Ron%20Carter%20-%20Where.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/09 - Ron Carter - Where.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F10%20-%20Ron%20Carter%20-%20Yes%20Indeed.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/10 - Ron Carter - Yes Indeed.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Four%20Classic%20Albums%20CD2/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FJazz%20Bassists%20-%20Four%20Classic%20Albums%20(2018%2C%20Avid)%2FCD2%2F11%20-%20Ron%20Carter%20-%20Saucer%20Eyes.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Jazz Bassists - Four Classic Albums (2018, Avid)/CD2/11 - Ron Carter - Saucer Eyes.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Cocktails%20at%20the%20Cotton%20Club/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FRon%20Carter%20-%20Cocktails%20at%20the%20Cotton%20Club%20(2013%2C%20Somethin'%20Else-Japan)%2FRon%20Carter%20-%20Cocktails%20at%20the%20Cotton%20Club.flac&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Ron Carter - Cocktails at the Cotton Club (2013, Somethin' Else-Japan)/Ron Carter - Cocktails at the Cotton Club.flac
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Cocktails%20at%20the%20Cotton%20Club/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FRon%20Carter%20-%20Cocktails%20at%20the%20Cotton%20Club%20(2013%2C%20Somethin'%20Else-Japan)%2FRon%20Carter%20-%20Cocktails%20at%20the%20Cotton%20Club.flac.cue&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Ron Carter - Cocktails at the Cotton Club (2013, Somethin' Else-Japan)/Ron Carter - Cocktails at the Cotton Club.flac.cue
Dec 13 12:19:58 volumio volumio[1941]: info: ALBUMART /albumart?cacheid=797&web=Ron%20Carter/Cocktails%20at%20the%20Cotton%20Club/extralarge&path=%2Fmnt%2FUSB%2F6745-2301%2FJAZZ%2FRon%20Carter%20-%20Cocktails%20at%20the%20Cotton%20Club%20(2013%2C%20Somethin'%20Else-Japan)%2FRon%20Carter%20-%20Cocktails%20at%20the%20Cotton%20Club.flac.cue&metadata=false
Dec 13 12:19:58 volumio volumio[1941]: info: URI /mnt/USB/6745-2301/JAZZ/Ron Carter - Cocktails at the Cotton Club (2013, Somethin' Else-Japan)/Ron Carter - Cocktails at the Cotton Club.flac.cue
Dec 13 12:19:58 volumio volumio[1941]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 13 12:19:58 volumio volumio[1941]: Error: Unable to resolve or reject the same promise twice
Dec 13 12:19:58 volumio volumio[1941]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Dec 13 12:19:58 volumio volumio[1941]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21
Dec 13 12:19:58 volumio volumio[1941]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Dec 13 12:19:58 volumio volumio[1941]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Dec 13 12:19:58 volumio volumio[1941]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Dec 13 12:19:58 volumio volumio[1941]: at Socket.emit (events.js:315:20)
Dec 13 12:19:58 volumio volumio[1941]: at addChunk (internal/streams/readable.js:309:12)
Dec 13 12:19:58 volumio volumio[1941]: at readableAddChunk (internal/streams/readable.js:280:11)
Dec 13 12:19:58 volumio volumio[1941]: at Socket.Readable.push (internal/streams/readable.js:223:10)
Dec 13 12:19:58 volumio volumio[1941]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23)
Dec 13 12:19:58 volumio volumio[1941]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 13 12:19:59 volumio sudo[2345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-13 12:18
Dec 13 12:19:59 volumio sudo[2345]: 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"