-- Logs begin at Fri 2024-11-22 19:30:49 CET, end at Fri 2024-11-22 19:32:48 CET. -- Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 23 Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 24 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 25 Nov 22 19:31:00 volumio sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 22 19:31:00 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:00 volumio volumio[1136]: info: Starting Shairport Sync Nov 22 19:31:00 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio volumio[1136]: info: Starting Shairport Sync Nov 22 19:31:00 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 19:31:00 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:00 volumio volumio[1136]: info: Starting Shairport Sync Nov 22 19:31:00 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. Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 26 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 27 Nov 22 19:31:00 volumio sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 19:31:00 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 28 Nov 22 19:31:00 volumio sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 19:31:00 volumio sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:00 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Nov 22 19:31:00 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 29 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 30 Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 31 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 32 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 33 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 34 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 35 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 36 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 37 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 38 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 39 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 40 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 41 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 42 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 43 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 44 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 45 Nov 22 19:31:00 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.30 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 46 Nov 22 19:31:00 volumio systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 22 19:31:00 volumio sudo[1372]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio systemd[1]: Reloading. Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: systemctl daemon-reload succeeded. Nov 22 19:31:00 volumio volumio[1136]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 22 19:31:00 volumio volumio[1136]: SPOTIFY: BQDAAHo1ZxyFVir4WzycgjwEfYW6k6bsqZTCJAsr17bq4FqiIrtp0Que6eDkWICthNo0i0hCk1Q-a35FTJb8xJ4MZrxAcNyf3drw9Ijtdce0CCjMMHKoWup9X2gZBz-MevH1kuhs4ADqaUVQBhn1rzC-kYLJJEl5g9SMl3Jrfa8fjy0dqYXcwJwQSU0GwSyq8H_o1I08UGAAQZZWw5GeHdrrD8ZvLgi-1wzyiYiCTY0Bf7iVOwuY0E6Ltg Nov 22 19:31:00 volumio volumio[1136]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 22 19:31:00 volumio volumio[1136]: info: New Spotify access token = BQDAAHo1ZxyFVir4WzycgjwEfYW6k6bsqZTCJAsr17bq4FqiIrtp0Que6eDkWICthNo0i0hCk1Q-a35FTJb8xJ4MZrxAcNyf3drw9Ijtdce0CCjMMHKoWup9X2gZBz-MevH1kuhs4ADqaUVQBhn1rzC-kYLJJEl5g9SMl3Jrfa8fjy0dqYXcwJwQSU0GwSyq8H_o1I08UGAAQZZWw5GeHdrrD8ZvLgi-1wzyiYiCTY0Bf7iVOwuY0E6Ltg Nov 22 19:31:00 volumio volumio[1136]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 22 19:31:00 volumio sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 22 19:31:00 volumio sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:00 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Nov 22 19:31:00 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:31:00 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. Nov 22 19:31:00 volumio volumio[1136]: SPOTIFY: User informations: {"country":"DE","display_name":"anvh12345","email":"simacc@t-online.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/anvh12345"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/anvh12345","id":"anvh12345","images":[],"product":"premium","type":"user","uri":"spotify:user:anvh12345"} Nov 22 19:31:00 volumio volumio[1136]: info: Spotify Successfully logged in Nov 22 19:31:00 volumio volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 19:31:00 volumio volumio[1136]: info: [1732300260373] CoreMusicLibrary::Adding element Spotify Nov 22 19:31:00 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 19:31:00 volumio volumio[1136]: Cannot find translation for source Spotify Nov 22 19:31:00 volumio sudo[1368]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 22 19:31:00 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 22 19:31:00 volumio systemd[1]: Started Volumio Kiosk. Nov 22 19:31:00 volumio sudo[1411]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 22 19:31:00 volumio systemd[1]: Started go-librespot Daemon. Nov 22 19:31:00 volumio sudo[1375]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: Volumio Kiosk started. Nov 22 19:31:00 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 22 19:31:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 22 19:31:00 volumio go-librespot[1430]: Librespot-go daemon starting... Nov 22 19:31:00 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Nov 22 19:31:00 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:31:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 22 19:31:00 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Nov 22 19:31:00 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:31:00 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio sudo[1407]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio volumio[1136]: info: Shairport-Sync Started Nov 22 19:31:00 volumio volumio[1136]: Error adding Membership: Error: addMembership EINVAL Nov 22 19:31:00 volumio volumio[1136]: info: Shairport-Sync Started Nov 22 19:31:00 volumio volumio[1136]: info: Shairport-Sync Started Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: X display number found: 0 Nov 22 19:31:00 volumio mpd[1351]: Nov 22 19:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: X display number found: 0 Nov 22 19:31:00 volumio systemd[1]: Started Music Player Daemon. Nov 22 19:31:00 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:00 volumio volumio[1136]: info: Completed starting Core Plugins Nov 22 19:31:00 volumio volumio[1136]: info: ------------------------------------------- Nov 22 19:31:00 volumio volumio[1136]: info: ----- MyVolumio plugins startup ---- Nov 22 19:31:00 volumio volumio[1136]: info: ------------------------------------------- Nov 22 19:31:00 volumio volumio[1136]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 22 19:31:00 volumio volumio[1136]: info: MPD running with PID1351 Nov 22 19:31:00 volumio volumio[1136]: ,establishing connection Nov 22 19:31:00 volumio startx[1427]: X.Org X Server 1.20.4 Nov 22 19:31:00 volumio startx[1427]: X Protocol Version 11, Revision 0 Nov 22 19:31:00 volumio startx[1427]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Nov 22 19:31:00 volumio startx[1427]: Current Operating System: Linux volumio 6.1.69-v8+ #1710 SMP PREEMPT Thu Dec 21 13:21:40 GMT 2023 aarch64 Nov 22 19:31:00 volumio startx[1427]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:E2:C3:CA vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Nov 22 19:31:00 volumio startx[1427]: Build Date: 04 April 2023 07:50:56AM Nov 22 19:31:00 volumio startx[1427]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Nov 22 19:31:00 volumio startx[1427]: Current version of pixman: 0.36.0 Nov 22 19:31:00 volumio startx[1427]: Before reporting problems, check http://wiki.x.org Nov 22 19:31:00 volumio startx[1427]: to make sure that you have the latest version. Nov 22 19:31:00 volumio startx[1427]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 22 19:31:00 volumio startx[1427]: (++) from command line, (!!) notice, (II) informational, Nov 22 19:31:00 volumio startx[1427]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 22 19:31:00 volumio startx[1427]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Nov 22 19:31:00 2024 Nov 22 19:31:00 volumio startx[1427]: (==) Using config directory: "/etc/X11/xorg.conf.d" Nov 22 19:31:00 volumio startx[1427]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 22 19:31:00 volumio volumio[1136]: error: MPD error: The expression evaluated to a falsy value: Nov 22 19:31:00 volumio volumio[1136]: assert.ok(self.idling) Nov 22 19:31:00 volumio volumio[1136]: error: The expression evaluated to a falsy value: Nov 22 19:31:00 volumio volumio[1136]: assert.ok(self.idling) Nov 22 19:31:00 volumio volumio[1136]: error: MPD error: The expression evaluated to a falsy value: Nov 22 19:31:00 volumio volumio[1136]: assert.ok(self.idling) Nov 22 19:31:00 volumio volumio[1136]: error: The expression evaluated to a falsy value: Nov 22 19:31:00 volumio volumio[1136]: assert.ok(self.idling) Nov 22 19:31:00 volumio volumio[1136]: error: updateQueue error: null Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 22 19:31:00 volumio volumio[1136]: info: touch_display: X display number found: 0 Nov 22 19:31:00 volumio go-librespot[1430]: time="2024-11-22T19:31:00+01:00" level=info msg="generated new device id: 33b04786ee01eaa925908d0edc1d4516ce492fe6" Nov 22 19:31:00 volumio go-librespot[1430]: time="2024-11-22T19:31:00+01:00" level=debug msg="stored credentials found for anvh12345" Nov 22 19:31:00 volumio startx[1427]: modprobe: FATAL: Module g2d_23 not found in directory /lib/modules/6.1.69-v8+ Nov 22 19:31:00 volumio go-librespot[1430]: time="2024-11-22T19:31:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 22 19:31:00 volumio go-librespot[1430]: time="2024-11-22T19:31:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 22 19:31:00 volumio go-librespot[1430]: time="2024-11-22T19:31:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 22 19:31:00 volumio go-librespot[1430]: time="2024-11-22T19:31:00+01:00" level=debug msg="zeroconf server listening on port 37439" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="obtained new client token: AAADWXf3CF5qAiD9a0sMIujNx6byOKb43ltg3goRXHu+PWJ6+gusTs6FBC3XYICjIIfagXOcE0F0WLeEvsfYpTcL9/n8/AeHZMADCBAKOjapACfuT90ZAMwDWztJpiLJjTgO4LLXCkaZrEKhDczPIDq1CTZftiJOJkPs9yDDIItNsIYhKnsEip4qFqjTELe5BJlXOFnas5AFJt81qr01fZ1tCI6agT4J8ZikDkOhmVwY0TQLbX5eO9gR+Yf41BM=" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="completed keyexchange" Nov 22 19:31:01 volumio startx[1427]: /usr/lib/xorg/Xorg: symbol lookup error: /usr/lib/xorg/modules/drivers/fbturbo_drv.so: undefined symbol: shadowUpdatePackedWeak Nov 22 19:31:01 volumio volumio[1136]: error: touch_display: Error determining the video outputs: Error: Command failed: /usr/bin/xrandr -display :0 Nov 22 19:31:01 volumio volumio[1136]: Can't open display :0 Nov 22 19:31:01 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="completed challenge" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="authenticated as anvh12345" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="authenticated as anvh12345" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="dealer connection opened" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="initializing zeroconf session, username: anvh12345" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="autoplay enabled: false" Nov 22 19:31:01 volumio go-librespot[1430]: time="2024-11-22T19:31:01+01:00" level=debug msg="received connection id: N2U4OWJhYjAtZmI2Yi00NTViLTliYWItZTg3NjgyZTIyYzcyK2RlYWxlcit0Y3A6Ly8wYWNhNThhOC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArN0Y0RDdDMDVGQTk3RUYwRTFFMEYyOTQxQTU0RERGNTVEODExQkM4OUVEOUJGMTgzNEVBN0EyQTY5Njk1RDE1QQ==" Nov 22 19:31:02 volumio go-librespot[1430]: time="2024-11-22T19:31:02+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 22 19:31:03 volumio volumio[1136]: info: go-librespot daemon successfully initialized Nov 22 19:31:03 volumio volumio-remote-updater[881]: [2024-11-22 19:31:03] [connect] Successful connection Nov 22 19:31:03 volumio volumio-remote-updater[881]: [2024-11-22 19:31:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732300263 101 Nov 22 19:31:03 volumio volumio[1136]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 47 Nov 22 19:31:06 volumio volumio[1136]: info: Initializing connection to go-librespot Websocket Nov 22 19:31:06 volumio go-librespot[1430]: time="2024-11-22T19:31:06+01:00" level=debug msg="new websocket client" Nov 22 19:31:06 volumio volumio[1136]: info: Connection to go-librespot Websocket established Nov 22 19:31:07 volumio sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 19:31:07 volumio sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:08 volumio sudo[1483]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:08 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 19:31:08 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:08 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:08 volumio sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 22 19:31:08 volumio sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:08 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 22 19:31:08 volumio sudo[1489]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:08 volumio volumio[1136]: info: Upmpdcli Daemon Started Nov 22 19:31:08 volumio volumio[1491]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 22 19:31:09 volumio volumio[1136]: info: Adding plugin bluetooth to MyMusic Plugins Nov 22 19:31:09 volumio volumio[1136]: info: Adding plugin multiroom to MyMusic Plugins Nov 22 19:31:09 volumio volumio[1136]: info: Adding plugin metavolumio to MyMusic Plugins Nov 22 19:31:09 volumio volumio[1136]: info: Adding plugin cd_controller to MyMusic Plugins Nov 22 19:31:09 volumio volumio[1136]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 22 19:31:09 volumio volumio[1136]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 22 19:31:09 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:09 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:09 volumio volumio[1136]: info: Starting MyVolumio Remote Streaming Endpoints Nov 22 19:31:09 volumio volumio[1136]: info: MyVolumio login type: Token Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 22 19:31:09 volumio volumio[1136]: info: Starting Streaming Service Transparent Proxy Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 22 19:31:09 volumio volumio[1136]: info: Streaming services startup Nov 22 19:31:09 volumio volumio[1136]: info: Starting Streaming Daemon Nov 22 19:31:09 volumio sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 22 19:31:09 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:09 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:09 volumio volumio[1136]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 22 19:31:09 volumio volumio[1136]: info: Getting Spotify volume Nov 22 19:31:09 volumio volumio[1136]: error: Cannot start Volumio Streaming Daemon Nov 22 19:31:09 volumio volumio[1136]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 22 19:31:09 volumio volumio[1136]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 22 19:31:09 volumio volumio[1136]: info: Spotify volume: 100 Nov 22 19:31:09 volumio volumio[1136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 48 Nov 22 19:31:09 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Nov 22 19:31:09 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:31:09 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:09 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 100 Nov 22 19:31:09 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:09 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 22 19:31:09 volumio volumio[1136]: info: Setting Spotify Volume from Volumio: 20 Nov 22 19:31:10 volumio volumio[1136]: STREAMING PROXY: Starting server on port 3245 Nov 22 19:31:10 volumio volumio[1136]: Node JS runtime: 14 Nov 22 19:31:10 volumio volumio[1136]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 22 19:31:11 volumio volumio[1136]: SPOTIFY: SETTING SPOTIFY VOLUME 20 Nov 22 19:31:11 volumio volumio[1136]: info: Sending Spotify command with payload to local API: /player/volume Nov 22 19:31:11 volumio volumio[1136]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Nov 22 19:31:11 volumio volumio[1136]: info: MyVolumio token set successfully Nov 22 19:31:11 volumio volumio[1136]: info: MYVOLUMIO: Adding device Nov 22 19:31:11 volumio volumio[1136]: info: MYVOLUMIO: Evaluating Server Nov 22 19:31:12 volumio volumio[1136]: info: MyVolumio status changed Nov 22 19:31:12 volumio volumio[1136]: info: Streaming services startup Nov 22 19:31:12 volumio volumio[1136]: info: Starting Streaming Daemon Nov 22 19:31:12 volumio volumio[1136]: info: Removing browser output: myVolumio user plan is not superstar Nov 22 19:31:12 volumio volumio[1136]: info: Removing audio output: Nov 22 19:31:12 volumio volumio[1136]: info: Stoppping Tunnel 1 Nov 22 19:31:12 volumio sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 22 19:31:12 volumio sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:12 volumio sudo[1539]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:12 volumio sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 22 19:31:12 volumio sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:31:12 volumio volumio[1136]: error: Cannot start Volumio Streaming Daemon Nov 22 19:31:12 volumio volumio[1136]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 22 19:31:12 volumio volumio[1136]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 22 19:31:12 volumio sudo[1542]: pam_unix(sudo:session): session closed for user root Nov 22 19:31:12 volumio volumio[1136]: info: Remote SSH Stopped Nov 22 19:31:13 volumio volumio[1136]: info: Setting Geolocation for MyVolumio to eu4 Nov 22 19:31:13 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:13 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:13 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:13 volumio volumio[1491]: .............................................................................................................++++ Nov 22 19:31:14 volumio volumio[1136]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 22 19:31:14 volumio volumio[1136]: info: Updating MyVolumio device info Nov 22 19:31:14 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:14 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:14 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:14 volumio volumio[1136]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 22 19:31:15 volumio volumio[1491]: .....................................++++ Nov 22 19:31:15 volumio volumio[1491]: e is 65537 (0x010001) Nov 22 19:31:15 volumio volumio[1491]: writing RSA key Nov 22 19:31:15 volumio startx[1427]: xinit: giving up Nov 22 19:31:15 volumio startx[1427]: xinit: unable to connect to X server: Cannot assign requested address Nov 22 19:31:15 volumio startx[1427]: xinit: server error Nov 22 19:31:15 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Nov 22 19:31:15 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Nov 22 19:31:18 volumio volumio[1136]: info: MYVOLUMIO: Adding device Nov 22 19:31:18 volumio volumio[1136]: info: MYVOLUMIO: Evaluating Server Nov 22 19:31:19 volumio volumio[1136]: info: Setting Geolocation for MyVolumio to eu7 Nov 22 19:31:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:20 volumio volumio[1136]: info: Updating MyVolumio device info Nov 22 19:31:20 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:20 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:20 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:20 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 22 19:31:21 volumio volumio[1136]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 22 19:31:21 volumio volumio[1136]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 22 19:31:22 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Nov 22 19:31:24 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 19:31:24 volumio volumio[1136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Nov 22 19:31:24 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Nov 22 19:31:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:31:25 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 22 19:31:25 volumio volumio[1136]: info: CURURI: music-library Nov 22 19:31:25 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:31:25 volumio volumio[1136]: info: CoreCommandRouter::volumioGetQueue Nov 22 19:31:25 volumio volumio[1136]: info: CoreStateMachine::getQueue Nov 22 19:31:25 volumio volumio[1136]: info: CorePlayQueue::getQueue Nov 22 19:31:27 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 22 19:31:27 volumio volumio[1136]: info: CURURI: music-library/USB Nov 22 19:31:27 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:31:28 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 22 19:31:28 volumio volumio[1136]: info: CURURI: music-library/USB/7AC2-BFDE Nov 22 19:31:29 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::volumioGetBrowseSources Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 22 19:31:29 volumio volumio[1136]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 22 19:31:29 volumio volumio[1136]: info: Completed starting MyVolumio Plugin Nov 22 19:31:29 volumio volumio[1136]: info: BOOT COMPLETED Nov 22 19:31:29 volumio volumio[1136]: [Metrics] CommandRouter: 33s 108.28ms Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::volumiosetStartupVolume Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 19:31:29 volumio volumio[1136]: info: VolumeController:: Setting startup Volume 20 Nov 22 19:31:29 volumio volumio[1136]: info: VolumeController::SetAlsaVolume20 Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::Close All Modals sent Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::Close All Modals sent Nov 22 19:31:29 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:29 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:31:29 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:29 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:29 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:29 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:29 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:30 volumio volumio-remote-updater[881]: No test mode Nov 22 19:31:30 volumio volumio-remote-updater[881]: No alpha test mode Nov 22 19:31:30 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 22 19:31:30 volumio volumio[1136]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS

 

 

","title":"Update v3.779","updateavailable":true} Nov 22 19:31:30 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 22 19:31:30 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 22 19:31:30 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 22 19:31:30 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 22 19:31:34 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 22 19:31:34 volumio volumio[1136]: info: CURURI: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century Nov 22 19:31:34 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/01 - Beethoven - String Quartet Op.18 no. 6.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/02 - Rachmaninov - Piano Concerto No.3.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/03 - Berlioz - Les Troyens Act V, scene 2.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/05 - Puccini - Turandot - Signore, ascolta! (Act I).flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/06 - Puccini - Turandot - Tu che di gel sei cinta (Act III).flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/07 - Britten - A Ceremony of Carols II - Wolcum Yole!.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/08 - Britten - A Ceremony of Carols III - There is no rose.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/09 - Britten - Jubilate Deo.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/10 - Liszt - Mephisto Waltz No.1.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/11 - Dvorak - Cello Concerto, II Adagio ma non troppo.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/12 - Schubert - Winterreise I - Gute Nacht.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/13 - R.Strauss - Till Eulenspiegels Lustige Streiche (conclusion).flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/14 - Debussy - Images, set II.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/15 - Rossini - La scala di seta - Overture.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/16 - Verdi - Don Carli Act 3, scene2 - che parli tu di morte.flac Nov 22 19:31:34 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/Various - Great Artists of the Century.flac.m3u Nov 22 19:31:34 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/01 - Beethoven - String Quartet Op.18 no. 6.flac in service mpd Nov 22 19:31:34 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Beethoven/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F01%20-%20Beethoven%20-%20String%20Quartet%20Op.18%20no.%206.flac&metadata=false Nov 22 19:31:34 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/01 - Beethoven - String Quartet Op.18 no. 6.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/02 - Rachmaninov - Piano Concerto No.3.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Rachmaninov/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F02%20-%20Rachmaninov%20-%20Piano%20Concerto%20No.3.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/02 - Rachmaninov - Piano Concerto No.3.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/03 - Berlioz - Les Troyens Act V, scene 2.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Berlioz/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F03%20-%20Berlioz%20-%20Les%20Troyens%20Act%20V%2C%20scene%202.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/03 - Berlioz - Les Troyens Act V, scene 2.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Schubert/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F04%20-%20Schubert%20-%20Symphony%20No.9.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/05 - Puccini - Turandot - Signore, ascolta! (Act I).flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Puccini/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F05%20-%20Puccini%20-%20Turandot%20-%20Signore%2C%20ascolta!%20(Act%20I).flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/05 - Puccini - Turandot - Signore, ascolta! (Act I).flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/06 - Puccini - Turandot - Tu che di gel sei cinta (Act III).flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Puccini/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F06%20-%20Puccini%20-%20Turandot%20-%20Tu%20che%20di%20gel%20sei%20cinta%20(Act%20III).flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/06 - Puccini - Turandot - Tu che di gel sei cinta (Act III).flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/07 - Britten - A Ceremony of Carols II - Wolcum Yole!.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Britten/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F07%20-%20Britten%20-%20A%20Ceremony%20of%20Carols%20II%20-%20Wolcum%20Yole!.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/07 - Britten - A Ceremony of Carols II - Wolcum Yole!.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/08 - Britten - A Ceremony of Carols III - There is no rose.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Britten/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F08%20-%20Britten%20-%20A%20Ceremony%20of%20Carols%20III%20-%20There%20is%20no%20rose.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/08 - Britten - A Ceremony of Carols III - There is no rose.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/09 - Britten - Jubilate Deo.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Britten/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F09%20-%20Britten%20-%20Jubilate%20Deo.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/09 - Britten - Jubilate Deo.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/10 - Liszt - Mephisto Waltz No.1.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Liszt/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F10%20-%20Liszt%20-%20Mephisto%20Waltz%20No.1.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/10 - Liszt - Mephisto Waltz No.1.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/11 - Dvorak - Cello Concerto, II Adagio ma non troppo.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Dvorak/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F11%20-%20Dvorak%20-%20Cello%20Concerto%2C%20II%20Adagio%20ma%20non%20troppo.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/11 - Dvorak - Cello Concerto, II Adagio ma non troppo.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/12 - Schubert - Winterreise I - Gute Nacht.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Schubert/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F12%20-%20Schubert%20-%20Winterreise%20I%20-%20Gute%20Nacht.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/12 - Schubert - Winterreise I - Gute Nacht.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/13 - R.Strauss - Till Eulenspiegels Lustige Streiche (conclusion).flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=R.Strauss/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F13%20-%20R.Strauss%20-%20Till%20Eulenspiegels%20Lustige%20Streiche%20(conclusion).flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/13 - R.Strauss - Till Eulenspiegels Lustige Streiche (conclusion).flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/14 - Debussy - Images, set II.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Debussy/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F14%20-%20Debussy%20-%20Images%2C%20set%20II.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/14 - Debussy - Images, set II.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/15 - Rossini - La scala di seta - Overture.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Rossini/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F15%20-%20Rossini%20-%20La%20scala%20di%20seta%20-%20Overture.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/15 - Rossini - La scala di seta - Overture.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/16 - Verdi - Don Carli Act 3, scene2 - che parli tu di morte.flac in service mpd Nov 22 19:31:35 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Verdi/Great%20Artists%20of%20the%20Century/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FVarious%20-%20Great%20Artists%20of%20the%20Century%2F16%20-%20Verdi%20-%20Don%20Carli%20Act%203%2C%20scene2%20-%20che%20parli%20tu%20di%20morte.flac&metadata=false Nov 22 19:31:35 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Various - Great Artists of the Century/16 - Verdi - Don Carli Act 3, scene2 - che parli tu di morte.flac Nov 22 19:31:35 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/Various - Great Artists of the Century.flac.m3u in service mpd Nov 22 19:31:36 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::ClearQueue Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::stop Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::clearPlayQueue Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::saveQueue Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::addQueueItems Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::addQueueItems Nov 22 19:31:36 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/01 - Beethoven - String Quartet Op.18 no. 6.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/01 - Beethoven - String Quartet Op.18 no. 6.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/02 - Rachmaninov - Piano Concerto No.3.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/02 - Rachmaninov - Piano Concerto No.3.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/03 - Berlioz - Les Troyens Act V, scene 2.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/03 - Berlioz - Les Troyens Act V, scene 2.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/05 - Puccini - Turandot - Signore, ascolta! (Act I).flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/05 - Puccini - Turandot - Signore, ascolta! (Act I).flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/06 - Puccini - Turandot - Tu che di gel sei cinta (Act III).flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/06 - Puccini - Turandot - Tu che di gel sei cinta (Act III).flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/07 - Britten - A Ceremony of Carols II - Wolcum Yole!.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/07 - Britten - A Ceremony of Carols II - Wolcum Yole!.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/08 - Britten - A Ceremony of Carols III - There is no rose.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/08 - Britten - A Ceremony of Carols III - There is no rose.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/09 - Britten - Jubilate Deo.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/09 - Britten - Jubilate Deo.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/10 - Liszt - Mephisto Waltz No.1.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/10 - Liszt - Mephisto Waltz No.1.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/11 - Dvorak - Cello Concerto, II Adagio ma non troppo.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/11 - Dvorak - Cello Concerto, II Adagio ma non troppo.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/12 - Schubert - Winterreise I - Gute Nacht.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/12 - Schubert - Winterreise I - Gute Nacht.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/13 - R.Strauss - Till Eulenspiegels Lustige Streiche (conclusion).flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/13 - R.Strauss - Till Eulenspiegels Lustige Streiche (conclusion).flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/14 - Debussy - Images, set II.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/14 - Debussy - Images, set II.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/15 - Rossini - La scala di seta - Overture.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/15 - Rossini - La scala di seta - Overture.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/16 - Verdi - Don Carli Act 3, scene2 - che parli tu di morte.flac Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/16 - Verdi - Don Carli Act 3, scene2 - che parli tu di morte.flac Nov 22 19:31:36 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/Various - Great Artists of the Century.flac.m3u Nov 22 19:31:36 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Various - Great Artists of the Century/Various - Great Artists of the Century.flac.m3u Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::saveQueue Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPlay Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::play index 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::stop Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::play index undefined Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::startPlaybackTimer Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::clearAddPlayTracks USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand stop took 3 milliseconds Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:31:36 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand clear took 2 milliseconds Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac" Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:31:36 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:31:36 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:31:36 volumio volumio[1136]: error: updateQueue error: null Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:31:36 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 4ms Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand add "USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac" took 3 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 2ms Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 2ms Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:31:36 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:31:36 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 4ms Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand play took 3 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 2ms Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 3ms Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand status took 9 milliseconds Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand status took 9 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand status took 9 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony No.9","artist":"Schubert","album":"Great Artists of the Century","uri":"USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac","trackType":"flac"} Nov 22 19:31:36 volumio volumio[1136]: verbose: CURRENT POSITION 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 15ms Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:31:36 volumio volumio[1136]: info: Nov 22 19:31:36 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand status took 7 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"347 Kbps","isStreaming":false,"title":"Symphony No.9","artist":"Schubert","album":"Great Artists of the Century","uri":"USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac","trackType":"flac"} Nov 22 19:31:36 volumio volumio[1136]: verbose: CURRENT POSITION 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:31:36 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"347 Kbps","isStreaming":false,"title":"Symphony No.9","artist":"Schubert","album":"Great Artists of the Century","uri":"USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac","trackType":"flac"} Nov 22 19:31:36 volumio volumio[1136]: verbose: CURRENT POSITION 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:31:36 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 33ms Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 34ms Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 18 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 22 19:31:36 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:31:36 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"347 Kbps","isStreaming":false,"title":"Symphony No.9","artist":"Schubert","album":"Great Artists of the Century","uri":"USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac","trackType":"flac"} Nov 22 19:31:36 volumio volumio[1136]: verbose: CURRENT POSITION 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:31:36 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"611 Kbps","isStreaming":false,"title":"Symphony No.9","artist":"Schubert","album":"Great Artists of the Century","uri":"USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac","trackType":"flac"} Nov 22 19:31:36 volumio volumio[1136]: verbose: CURRENT POSITION 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:31:36 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"611 Kbps","isStreaming":false,"title":"Symphony No.9","artist":"Schubert","album":"Great Artists of the Century","uri":"USB/7AC2-BFDE/Various - Great Artists of the Century/04 - Schubert - Symphony No.9.flac","trackType":"flac"} Nov 22 19:31:36 volumio volumio[1136]: verbose: CURRENT POSITION 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:31:36 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:31:36 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:31:36 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 44ms Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 40ms Nov 22 19:31:36 volumio volumio[1136]: info: ------------------------------ 39ms Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:31:36 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:31:36 volumio volumio[1136]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :0 s reset dpms force on Nov 22 19:31:36 volumio volumio[1136]: /usr/bin/xset: unable to open display ":0" Nov 22 19:31:36 volumio volumio[1136]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 0 Nov 22 19:31:36 volumio volumio[1136]: /usr/bin/xset: unable to open display ":0" Nov 22 19:31:52 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 22 19:31:52 volumio volumio[1136]: info: CURURI: music-library Nov 22 19:31:52 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:31:57 volumio ntpd[1013]: Soliciting pool server 213.239.234.28 Nov 22 19:31:58 volumio ntpd[1013]: Soliciting pool server 88.198.200.96 Nov 22 19:31:58 volumio ntpd[1013]: Soliciting pool server 194.164.164.175 Nov 22 19:31:59 volumio ntpd[1013]: Soliciting pool server 135.125.205.191 Nov 22 19:31:59 volumio ntpd[1013]: Soliciting pool server 116.202.118.202 Nov 22 19:31:59 volumio ntpd[1013]: Soliciting pool server 194.50.19.204 Nov 22 19:32:00 volumio ntpd[1013]: Soliciting pool server 185.252.140.126 Nov 22 19:32:00 volumio ntpd[1013]: Soliciting pool server 128.140.109.119 Nov 22 19:32:00 volumio ntpd[1013]: Soliciting pool server 94.130.184.193 Nov 22 19:32:00 volumio ntpd[1013]: Soliciting pool server 141.144.241.16 Nov 22 19:32:00 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 22 19:32:00 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:32:01 volumio ntpd[1013]: Soliciting pool server 49.13.14.46 Nov 22 19:32:01 volumio ntpd[1013]: Soliciting pool server 78.46.102.180 Nov 22 19:32:01 volumio ntpd[1013]: Soliciting pool server 78.47.168.188 Nov 22 19:32:02 volumio ntpd[1013]: Soliciting pool server 80.153.195.191 Nov 22 19:32:02 volumio ntpd[1013]: Soliciting pool server 107.189.12.98 Nov 22 19:32:02 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 22 19:32:02 volumio volumio[1136]: info: Getting webradio selection Nov 22 19:32:03 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:32:03 volumio ntpd[1013]: Soliciting pool server 5.1.73.50 Nov 22 19:32:05 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::ClearQueue Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::stop Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::stPlaybackTimer Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::serviceStop Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrack 3 Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::serviceStop Nov 22 19:32:05 volumio volumio[1136]: info: ControllerMpd::stop Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::clearPlayQueue Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::saveQueue Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::addQueueItems Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::addQueueItems Nov 22 19:32:05 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:32:05 volumio volumio[1136]: info: Adding Item to queue: http://stream.radioparadise.com/flacm Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::saveQueue Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::volumioPlay Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::play index 0 Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::stop Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::play index undefined Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::startPlaybackTimer Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:05 volumio volumio[1136]: info: [1732300325862] ControllerWebradio::clearAddPlayTrack Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:05 volumio volumio[1136]: info: Nov 22 19:32:05 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:05 volumio volumio[1136]: info: sendMpdCommand stop took 21 milliseconds Nov 22 19:32:05 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:05 volumio volumio[1136]: info: sendMpdCommand stop took 12 milliseconds Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 19:32:05 volumio volumio[1136]: info: Nov 22 19:32:05 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:05 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:05 volumio volumio[1136]: info: Nov 22 19:32:05 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:05 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:05 volumio volumio[1136]: info: Nov 22 19:32:05 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:05 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:05 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Nov 22 19:32:05 volumio volumio[1136]: info: sendMpdCommand clear took 2 milliseconds Nov 22 19:32:05 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:05 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/flacm" Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:05 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:05 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 22 19:32:05 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:05 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:05 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:05 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:05 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 22 19:32:05 volumio volumio[1136]: info: ------------------------------ 13ms Nov 22 19:32:05 volumio volumio[1136]: info: ------------------------------ 13ms Nov 22 19:32:05 volumio volumio[1136]: info: Nov 22 19:32:05 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:05 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:05 volumio volumio[1136]: info: Nov 22 19:32:05 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:05 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:05 volumio volumio[1136]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 0 Nov 22 19:32:05 volumio volumio[1136]: /usr/bin/xset: unable to open display ":0" Nov 22 19:32:05 volumio volumio[1136]: error: updateQueue error: null Nov 22 19:32:05 volumio volumio[1136]: info: ------------------------------ 16ms Nov 22 19:32:05 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 15 milliseconds Nov 22 19:32:05 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:05 volumio volumio[1136]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 19:32:05 volumio volumio[1136]: info: ------------------------------ 20ms Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:05 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/flacm" Nov 22 19:32:06 volumio volumio[1136]: error: updateQueue error: null Nov 22 19:32:06 volumio volumio[1136]: error: updateQueue error: null Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:06 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 152ms Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 151ms Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand add "http://stream.radioparadise.com/flacm" took 1 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:06 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:06 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 4ms Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand play took 3 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 3ms Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 2ms Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 13 milliseconds Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 13 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 13 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 21ms Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 8 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:06 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:06 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 42ms Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 41ms Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 18 milliseconds Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:06 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:06 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:06 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 51ms Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 45ms Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 44ms Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :0 s reset dpms force on Nov 22 19:32:06 volumio volumio[1136]: /usr/bin/xset: unable to open display ":0" Nov 22 19:32:06 volumio volumio[1136]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 0 Nov 22 19:32:06 volumio volumio[1136]: /usr/bin/xset: unable to open display ":0" Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:06 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:06 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 2ms Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 1ms Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:06 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:06 volumio volumio[1136]: info: Nov 22 19:32:06 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 1ms Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand status took 0 milliseconds Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:06 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:06 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 20ms Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 18ms Nov 22 19:32:06 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 15 milliseconds Nov 22 19:32:06 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:06 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:06 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:06 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:06 volumio volumio[1136]: info: ------------------------------ 29ms Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:06 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:07 volumio ntpd[1013]: receive: Unexpected origin timestamp 0xeaeb4ca6.c8dacabd does not match aorg 0000000000.00000000 from server@94.130.184.193 xmt 0xeaeb4ca7.031aafff Nov 22 19:32:07 volumio ntpd[1013]: receive: Unexpected origin timestamp 0xeaeb4ca6.c8d4fdfa does not match aorg 0000000000.00000000 from server@80.153.195.191 xmt 0xeaeb4ca7.03171246 Nov 22 19:32:07 volumio ntpd[1013]: receive: Unexpected origin timestamp 0xeaeb4ca6.c8dbf566 does not match aorg 0000000000.00000000 from server@194.50.19.204 xmt 0xeaeb4ca7.026eda16 Nov 22 19:32:07 volumio ntpd[1013]: receive: Unexpected origin timestamp 0xeaeb4ca6.c8d97335 does not match aorg 0000000000.00000000 from server@49.13.14.46 xmt 0xeaeb4ca7.03757a58 Nov 22 19:32:07 volumio ntpd[1013]: receive: Unexpected origin timestamp 0xeaeb4ca6.c8d82940 does not match aorg 0000000000.00000000 from server@78.46.102.180 xmt 0xeaeb4ca7.03acdd0a Nov 22 19:32:07 volumio ntpd[1013]: receive: Unexpected origin timestamp 0xeaeb4ca6.c8d66897 does not match aorg 0000000000.00000000 from server@78.47.168.188 xmt 0xeaeb4ca7.03b898be Nov 22 19:32:07 volumio ntpd[1013]: receive: Unexpected origin timestamp 0xeaeb4ca6.c8de8002 does not match aorg 0000000000.00000000 from server@194.164.164.175 xmt 0xeaeb4ca7.0620969c Nov 22 19:32:10 volumio volumio[1136]: info: Nov 22 19:32:10 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:10 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:10 volumio volumio[1136]: info: Nov 22 19:32:10 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:10 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:10 volumio volumio[1136]: info: Nov 22 19:32:10 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:10 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:10 volumio volumio[1136]: info: Nov 22 19:32:10 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:10 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:10 volumio volumio[1136]: info: Nov 22 19:32:10 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:10 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:10 volumio volumio[1136]: info: Nov 22 19:32:10 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:10 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:10 volumio volumio[1136]: info: ------------------------------ 3ms Nov 22 19:32:10 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Nov 22 19:32:10 volumio volumio[1136]: info: ------------------------------ 2ms Nov 22 19:32:10 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:10 volumio volumio[1136]: info: ------------------------------ 2ms Nov 22 19:32:10 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:10 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 19:32:10 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 19:32:10 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:10 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:10 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:10 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:10 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hey Rosetta! - Young Glass","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:10 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:10 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:10 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:10 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:10 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4708,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hey Rosetta! - Young Glass","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:10 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:10 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:10 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:10 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:10 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4708,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hey Rosetta! - Young Glass","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:10 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:10 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:10 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:10 volumio volumio[1136]: info: ------------------------------ 33ms Nov 22 19:32:10 volumio volumio[1136]: info: ------------------------------ 32ms Nov 22 19:32:10 volumio volumio[1136]: info: ------------------------------ 31ms Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:10 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:15 volumio volumio[1136]: info: Nov 22 19:32:15 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:15 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:15 volumio volumio[1136]: info: Nov 22 19:32:15 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:15 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:15 volumio volumio[1136]: info: Nov 22 19:32:15 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:15 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:15 volumio volumio[1136]: info: Nov 22 19:32:15 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:15 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:15 volumio volumio[1136]: info: Nov 22 19:32:15 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:15 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:15 volumio volumio[1136]: info: Nov 22 19:32:15 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:15 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:15 volumio volumio[1136]: info: ------------------------------ 6ms Nov 22 19:32:15 volumio volumio[1136]: info: sendMpdCommand status took 4 milliseconds Nov 22 19:32:15 volumio volumio[1136]: info: ------------------------------ 3ms Nov 22 19:32:15 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Nov 22 19:32:15 volumio volumio[1136]: info: ------------------------------ 2ms Nov 22 19:32:15 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:15 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 19:32:15 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 19:32:15 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:15 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:15 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:15 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:15 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9332,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tonic - Lemon Parade","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:15 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:15 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:15 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:15 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:15 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9332,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tonic - Lemon Parade","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:15 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:15 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:15 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:15 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:15 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9332,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tonic - Lemon Parade","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Nov 22 19:32:15 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:15 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:15 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:15 volumio volumio[1136]: info: ------------------------------ 36ms Nov 22 19:32:15 volumio volumio[1136]: info: ------------------------------ 35ms Nov 22 19:32:15 volumio volumio[1136]: info: ------------------------------ 34ms Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:15 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 22 19:32:19 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 22 19:32:32 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 22 19:32:32 volumio volumio[1136]: info: CURURI: albums:// Nov 22 19:32:32 volumio volumio[1136]: info: listAlbums - loading Albums from cache Nov 22 19:32:32 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:32:36 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 22 19:32:36 volumio volumio[1136]: info: CURURI: albums://Chris%20Rea/LIVE%20TV Nov 22 19:32:36 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:32:36 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac Nov 22 19:32:36 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/02 - Chris Rea - Track 2.flac Nov 22 19:32:36 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/03 - Chris Rea - Track 3.flac Nov 22 19:32:36 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/04 - Chris Rea - Track 4.flac Nov 22 19:32:36 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/05 - Chris Rea - Track 5.flac Nov 22 19:32:36 volumio volumio[1136]: info: Preloading song: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/06 - Chris Rea - Track 6.flac Nov 22 19:32:36 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac in service mpd Nov 22 19:32:36 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Chris%20Rea/LIVE%20TV/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FChris%20Rea%20-%20LIVE%20TV%2F01%20-%20Chris%20Rea%20-%20Track%201.flac&metadata=false Nov 22 19:32:36 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac Nov 22 19:32:36 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/02 - Chris Rea - Track 2.flac in service mpd Nov 22 19:32:36 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Chris%20Rea/LIVE%20TV/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FChris%20Rea%20-%20LIVE%20TV%2F02%20-%20Chris%20Rea%20-%20Track%202.flac&metadata=false Nov 22 19:32:36 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Chris Rea - LIVE TV/02 - Chris Rea - Track 2.flac Nov 22 19:32:36 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/03 - Chris Rea - Track 3.flac in service mpd Nov 22 19:32:36 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Chris%20Rea/LIVE%20TV/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FChris%20Rea%20-%20LIVE%20TV%2F03%20-%20Chris%20Rea%20-%20Track%203.flac&metadata=false Nov 22 19:32:36 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Chris Rea - LIVE TV/03 - Chris Rea - Track 3.flac Nov 22 19:32:36 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/04 - Chris Rea - Track 4.flac in service mpd Nov 22 19:32:36 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Chris%20Rea/LIVE%20TV/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FChris%20Rea%20-%20LIVE%20TV%2F04%20-%20Chris%20Rea%20-%20Track%204.flac&metadata=false Nov 22 19:32:36 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Chris Rea - LIVE TV/04 - Chris Rea - Track 4.flac Nov 22 19:32:36 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/05 - Chris Rea - Track 5.flac in service mpd Nov 22 19:32:36 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Chris%20Rea/LIVE%20TV/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FChris%20Rea%20-%20LIVE%20TV%2F05%20-%20Chris%20Rea%20-%20Track%205.flac&metadata=false Nov 22 19:32:36 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Chris Rea - LIVE TV/05 - Chris Rea - Track 5.flac Nov 22 19:32:36 volumio volumio[1136]: info: Exploding uri music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/06 - Chris Rea - Track 6.flac in service mpd Nov 22 19:32:36 volumio volumio[1136]: info: ALBUMART /albumart?cacheid=610&web=Chris%20Rea/LIVE%20TV/extralarge&path=%2Fmnt%2FUSB%2F7AC2-BFDE%2FChris%20Rea%20-%20LIVE%20TV%2F06%20-%20Chris%20Rea%20-%20Track%206.flac&metadata=false Nov 22 19:32:36 volumio volumio[1136]: info: URI /mnt/USB/7AC2-BFDE/Chris Rea - LIVE TV/06 - Chris Rea - Track 6.flac Nov 22 19:32:39 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::ClearQueue Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::stop Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::stPlaybackTimer Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::serviceStop Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::serviceStop Nov 22 19:32:39 volumio volumio[1136]: info: [1732300359562] ControllerWebradio::stop Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::clearPlayQueue Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::saveQueue Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::addQueueItems Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::addQueueItems Nov 22 19:32:39 volumio volumio[1136]: info: Preload queue cleared Nov 22 19:32:39 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac Nov 22 19:32:39 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac Nov 22 19:32:39 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/02 - Chris Rea - Track 2.flac Nov 22 19:32:39 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/02 - Chris Rea - Track 2.flac Nov 22 19:32:39 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/03 - Chris Rea - Track 3.flac Nov 22 19:32:39 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/03 - Chris Rea - Track 3.flac Nov 22 19:32:39 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/04 - Chris Rea - Track 4.flac Nov 22 19:32:39 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/04 - Chris Rea - Track 4.flac Nov 22 19:32:39 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/05 - Chris Rea - Track 5.flac Nov 22 19:32:39 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/05 - Chris Rea - Track 5.flac Nov 22 19:32:39 volumio volumio[1136]: info: Adding Item to queue: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/06 - Chris Rea - Track 6.flac Nov 22 19:32:39 volumio volumio[1136]: info: Using cached record of: music-library/USB/7AC2-BFDE/Chris Rea - LIVE TV/06 - Chris Rea - Track 6.flac Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::saveQueue Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPlay Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::play index 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::stop Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::play index undefined Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::startPlaybackTimer Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::clearAddPlayTracks USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand stop took 11 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand stop took 11 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 9 milliseconds Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:39 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand clear took 2 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac" Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: 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} Nov 22 19:32:39 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::syncState stateService stop Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:39 volumio volumio[1136]: info: No code Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: 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} Nov 22 19:32:39 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::syncState stateService stop Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:39 volumio volumio[1136]: info: No code Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 18ms Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 22ms Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 21ms Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:39 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:39 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:39 volumio volumio[1136]: error: updateQueue error: null Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:39 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 23ms Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand add "USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac" took 22 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 1ms Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 1ms Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:39 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Nov 22 19:32:39 volumio volumio[1136]: info: Ignoring MPD Status Update Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:39 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:39 volumio volumio[1136]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 0 Nov 22 19:32:39 volumio volumio[1136]: /usr/bin/xset: unable to open display ":0" Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 8ms Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand play took 8 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 8ms Nov 22 19:32:39 volumio volumio[1136]: info: ------------------------------ 7ms Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: Nov 22 19:32:39 volumio volumio[1136]: ---------------------------- MPD announces state update: player Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::getState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:39 volumio volumio[1136]: info: sendMpdCommand status took 1 milliseconds Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::parseState Nov 22 19:32:39 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"Chris Rea","album":"LIVE TV","uri":"USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac","trackType":"flac"} Nov 22 19:32:39 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Nov 22 19:32:39 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"Chris Rea","album":"LIVE TV","uri":"USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac","trackType":"flac"} Nov 22 19:32:39 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:39 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:39 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:39 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:39 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"Chris Rea","album":"LIVE TV","uri":"USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac","trackType":"flac"} Nov 22 19:32:40 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:40 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: ------------------------------ 14ms Nov 22 19:32:40 volumio volumio[1136]: info: ------------------------------ 25ms Nov 22 19:32:40 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 21 milliseconds Nov 22 19:32:40 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 22 19:32:40 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Nov 22 19:32:40 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"572 Kbps","isStreaming":false,"title":"Track 1","artist":"Chris Rea","album":"LIVE TV","uri":"USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac","trackType":"flac"} Nov 22 19:32:40 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:40 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"466 Kbps","isStreaming":false,"title":"Track 1","artist":"Chris Rea","album":"LIVE TV","uri":"USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac","trackType":"flac"} Nov 22 19:32:40 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:40 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: ControllerMpd::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"426 Kbps","isStreaming":false,"title":"Track 1","artist":"Chris Rea","album":"LIVE TV","uri":"USB/7AC2-BFDE/Chris Rea - LIVE TV/01 - Chris Rea - Track 1.flac","trackType":"flac"} Nov 22 19:32:40 volumio volumio[1136]: verbose: CURRENT POSITION 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Nov 22 19:32:40 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: CoreStateMachine::pushState Nov 22 19:32:40 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Nov 22 19:32:40 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Nov 22 19:32:40 volumio volumio[1136]: info: ------------------------------ 43ms Nov 22 19:32:40 volumio volumio[1136]: info: ------------------------------ 42ms Nov 22 19:32:40 volumio volumio[1136]: info: ------------------------------ 42ms Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: SPOTIFY VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: VOLUMIO VOLUME 20 Nov 22 19:32:40 volumio volumio[1136]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 22 19:32:40 volumio volumio[1136]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :0 s reset dpms force on Nov 22 19:32:40 volumio volumio[1136]: /usr/bin/xset: unable to open display ":0" Nov 22 19:32:40 volumio volumio[1136]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 0 Nov 22 19:32:40 volumio volumio[1136]: /usr/bin/xset: unable to open display ":0" Nov 22 19:32:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 19:32:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 22 19:32:48 volumio sudo[1815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 22 19:32:48 volumio sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:32:48 volumio sudo[1815]: pam_unix(sudo:session): session closed for user root Nov 22 19:32:48 volumio sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Nov 22 19:32:48 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:32:48 volumio sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Nov 22 19:32:48 volumio sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:32:48 volumio systemd[1]: Created slice system-getty.slice. Nov 22 19:32:48 volumio volumio[1136]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 22 19:32:48 volumio volumio[1136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 19:32:48 volumio volumio[1136]: TypeError: Cannot read property 'split' of undefined Nov 22 19:32:48 volumio volumio[1136]: at /data/plugins/user_interface/touch_display/index.js:1258:37 Nov 22 19:32:48 volumio volumio[1136]: at ChildProcess.exithandler (child_process.js:299:7) Nov 22 19:32:48 volumio volumio[1136]: at ChildProcess.emit (events.js:315:20) Nov 22 19:32:48 volumio volumio[1136]: at maybeClose (internal/child_process.js:1048:16) Nov 22 19:32:48 volumio volumio[1136]: at Socket. (internal/child_process.js:439:11) Nov 22 19:32:48 volumio volumio[1136]: at Socket.emit (events.js:315:20) Nov 22 19:32:48 volumio volumio[1136]: at Pipe. (net.js:673:12) Nov 22 19:32:48 volumio volumio[1136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 19:32:48 volumio sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Nov 22 19:32:48 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 19:32:48 volumio systemd[1]: Started Getty on tty1. Nov 22 19:32:48 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root Nov 22 19:32:48 volumio systemd[1]: Reloading. Nov 22 19:32:48 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. Nov 22 19:32:48 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 19:31 Nov 22 19:32:48 volumio sudo[1849]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"