Fri Sep 18 15:26:09 UTC 2020
Description="'Spotify'"
# cat /proc/version ---------------
Linux version 4.19.118-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1311 SMP Mon Apr 27 14:21:24 BST 2020
# cat /etc/os-release ---------------
PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)"
NAME="Raspbian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
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="ceaca221623bd3cfdc448100963369088321c5bb"
VOLUMIO_FE_VERSION="4917998f86709e30d6499627aa39df0350cb55f8"
VOLUMIO_BE_VERSION="2eb1d573179cee7a262e71ad6e6f6c07489b563f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed Jul 29 20:47:51 CEST 2020"
VOLUMIO_VERSION="2.806"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="a5dbec2d8b23cb06ebf9780d5fc2c84f"
# ifconfig ---------------
eth0 Link encap:Ethernet HWaddr b8:27:eb:8d:87:9b
inet addr:192.168.0.10 Bcast:192.168.0.255 Mask:255.255.255.0
UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
RX packets:49612832 errors:0 dropped:2720 overruns:0 frame:0
TX packets:12289660 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:906601252 (864.6 MiB) TX bytes:1094758326 (1.0 GiB)
lo Link encap:Local Loopback
inet addr:127.0.0.1 Mask:255.0.0.0
UP LOOPBACK RUNNING MTU:65536 Metric:1
RX packets:1408598 errors:0 dropped:0 overruns:0 frame:0
TX packets:1408598 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:123943802 (118.2 MiB) TX bytes:123943802 (118.2 MiB)
# sudo iwconfig ---------------
eth0 no wireless extensions.
lo no wireless extensions.
wlan0 IEEE 802.11 ESSID:off/any
Mode:Managed Access Point: Not-Associated
Retry short limit:7 RTS thr:off Fragment thr:off
Encryption key:off
Power Management:off
# aplay -l ---------------
**** List of PLAYBACK Hardware Devices ****
card 0: ALSA [bcm2835 ALSA], device 0: bcm2835 ALSA [bcm2835 ALSA]
Subdevices: 4/4
Subdevice #0: subdevice #0
Subdevice #1: subdevice #1
Subdevice #2: subdevice #2
Subdevice #3: subdevice #3
card 0: ALSA [bcm2835 ALSA], device 1: bcm2835 IEC958/HDMI [bcm2835 IEC958/HDMI]
Subdevices: 1/1
Subdevice #0: subdevice #0
card 0: ALSA [bcm2835 ALSA], device 2: bcm2835 IEC958/HDMI1 [bcm2835 IEC958/HDMI1]
Subdevices: 1/1
Subdevice #0: subdevice #0
card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones]
Subdevices: 3/3
Subdevice #0: subdevice #0
Subdevice #1: subdevice #1
Subdevice #2: subdevice #2
card 2: BossDAC [BossDAC], device 0: Boss DAC HiFi [Master] pcm512x-hifi-0 [Boss DAC HiFi [Master] pcm512x-hifi-0]
Subdevices: 1/1
Subdevice #0: subdevice #0
# ps -ef ---------------
UID PID PPID C STIME TTY TIME CMD
root 1 0 0 Aug13 ? 00:00:52 /sbin/init
root 2 0 0 Aug13 ? 00:00:02 [kthreadd]
root 3 2 0 Aug13 ? 00:00:00 [rcu_gp]
root 4 2 0 Aug13 ? 00:00:00 [rcu_par_gp]
root 8 2 0 Aug13 ? 00:00:00 [mm_percpu_wq]
root 9 2 0 Aug13 ? 00:01:32 [ksoftirqd/0]
root 10 2 0 Aug13 ? 00:02:38 [rcu_sched]
root 11 2 0 Aug13 ? 00:00:00 [rcu_bh]
root 12 2 0 Aug13 ? 00:00:00 [migration/0]
root 13 2 0 Aug13 ? 00:00:00 [cpuhp/0]
root 14 2 0 Aug13 ? 00:00:00 [cpuhp/1]
root 15 2 0 Aug13 ? 00:00:00 [migration/1]
root 16 2 0 Aug13 ? 00:00:02 [ksoftirqd/1]
root 19 2 0 Aug13 ? 00:00:00 [cpuhp/2]
root 20 2 0 Aug13 ? 00:00:00 [migration/2]
root 21 2 0 Aug13 ? 00:00:01 [ksoftirqd/2]
root 24 2 0 Aug13 ? 00:00:00 [cpuhp/3]
root 25 2 0 Aug13 ? 00:00:00 [migration/3]
root 26 2 0 Aug13 ? 00:00:34 [ksoftirqd/3]
root 28 2 0 Aug13 ? 00:00:07 [kworker/3:0H-kb]
root 29 2 0 Aug13 ? 00:00:00 [kdevtmpfs]
root 30 2 0 Aug13 ? 00:00:00 [netns]
root 34 2 0 Aug13 ? 00:00:02 [khungtaskd]
root 35 2 0 Aug13 ? 00:00:00 [oom_reaper]
root 36 2 0 Aug13 ? 00:00:00 [writeback]
root 37 2 0 Aug13 ? 00:00:00 [kcompactd0]
root 38 2 0 Aug13 ? 00:00:00 [crypto]
root 39 2 0 Aug13 ? 00:00:00 [kblockd]
root 40 2 0 Aug13 ? 00:00:00 [watchdogd]
root 41 2 0 Aug13 ? 00:00:00 [rpciod]
root 42 2 0 Aug13 ? 00:00:00 [kworker/u9:0-hc]
root 43 2 0 Aug13 ? 00:00:00 [xprtiod]
root 47 2 0 Aug13 ? 00:00:00 [kswapd0]
root 48 2 0 Aug13 ? 00:00:00 [nfsiod]
root 70 2 0 Aug13 ? 00:00:00 [kthrotld]
root 71 2 0 Aug13 ? 00:00:00 [iscsi_eh]
root 72 2 0 Aug13 ? 00:00:00 [dwc_otg]
root 73 2 0 Aug13 ? 00:00:00 [DWC Notificatio]
root 74 2 0 Aug13 ? 00:00:00 [vchiq-slot/0]
root 75 2 0 Aug13 ? 00:00:00 [vchiq-recy/0]
root 76 2 0 Aug13 ? 00:00:00 [vchiq-sync/0]
root 77 2 0 Aug13 ? 00:00:00 [vchiq-keep/0]
root 78 2 0 Aug13 ? 00:00:00 [SMIO]
root 80 2 0 Aug13 ? 00:00:00 [irq/86-mmc1]
root 89 2 0 Aug13 ? 00:00:00 [mmc_complete]
root 91 2 0 Aug13 ? 00:00:28 [kworker/0:1H-mm]
root 119 2 0 Aug13 ? 00:00:00 [jbd2/mmcblk0p2-]
root 120 2 0 Aug13 ? 00:00:00 [ext4-rsv-conver]
root 121 2 0 Aug13 ? 00:00:18 [kworker/1:1H-kb]
root 123 2 0 Aug13 ? 00:00:00 [scsi_eh_0]
root 124 2 0 Aug13 ? 00:00:00 [scsi_tmf_0]
root 125 2 0 Aug13 ? 00:01:04 [usb-storage]
root 128 2 0 Aug13 ? 00:00:00 [irq/166-usb-001]
root 141 2 0 Aug13 ? 00:00:00 [loop0]
root 144 2 0 Aug13 ? 00:00:10 [kworker/2:1H-kb]
root 161 2 0 Aug13 ? 00:00:37 [jbd2/mmcblk0p3-]
root 162 2 0 Aug13 ? 00:00:00 [ext4-rsv-conver]
root 171 2 0 Aug13 ? 00:00:00 [ipv6_addrconf]
root 198 1 0 Aug13 ? 00:02:20 /lib/systemd/systemd-journald
root 226 1 0 Aug13 ? 00:00:00 /lib/systemd/systemd-udevd
root 262 2 0 Aug13 ? 00:00:00 [SMIO]
root 334 2 0 Aug13 ? 00:00:00 [mmal-vchiq]
root 335 2 0 Aug13 ? 00:00:00 [mmal-vchiq]
root 337 2 0 Aug13 ? 00:00:00 [mmal-vchiq]
root 355 2 0 Aug13 ? 00:00:00 [cfg80211]
root 368 2 0 Aug13 ? 00:00:00 [brcmf_wq/mmc1:0]
root 370 2 0 Aug13 ? 00:00:00 [brcmf_wdog/mmc1]
root 575 1 0 Aug13 ? 00:00:04 /sbin/rpcbind -w
statd 585 1 0 Aug13 ? 00:00:00 /sbin/rpc.statd
root 597 1 0 Aug13 ? 00:00:00 /usr/sbin/rpc.idmapd
root 599 1 0 Aug13 ? 00:01:22 /usr/sbin/haveged --Foreground --verbose=1 --write=1024
volumio 603 1 0 Aug13 ? 00:00:00 /usr/bin/udisks-glue --foreground
volumio 608 1 0 Aug13 ? 00:00:48 /usr/local/bin/volumio-remote-updater
root 609 1 0 Aug13 ? 00:05:48 /bin/bash /bin/volumiologrotate
root 636 1 0 Aug13 ? 00:00:12 /lib/systemd/systemd-logind
avahi 668 1 0 Aug13 ? 00:02:05 avahi-daemon: running [volumio.local]
mpd 687 1 0 Aug13 ? 00:00:01 /usr/bin/mpd --no-daemon
message+ 690 1 0 Aug13 ? 00:00:02 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation
nobody 719 1 0 Aug13 ? 00:00:12 /usr/sbin/thd --daemon --triggers /etc/triggerhappy/triggers.d/ --socket /var/run/thd.socket --pidfile /var/run/thd.pid --user nobody /dev/input/event*
root 736 1 0 Aug13 ? 00:23:11 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I
root 767 1 0 Aug13 ? 00:00:00 /usr/sbin/sshd -D
root 770 1 0 Aug13 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - b8:27:eb:27:2d:31
root 773 2 0 Aug13 ? 00:00:00 [kworker/u9:2-hc]
ntp 776 1 0 Aug13 ? 00:02:21 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:104
avahi 785 668 0 Aug13 ? 00:00:00 avahi-daemon: chroot helper
root 786 1 0 Aug13 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug
root 788 1 0 Aug13 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap
root 797 786 0 Aug13 ? 00:00:00 udisks-daemon: not polling any devices
root 811 1 0 Aug13 tty1 00:00:00 /sbin/agetty --noclear tty1 linux
root 817 1 0 Aug13 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug
root 881 1 0 Aug13 ? 00:06:02 /usr/sbin/nmbd -D
root 882 881 0 Aug13 ? 00:00:00 /usr/sbin/nmbd -D
root 894 1 0 Aug13 ? 00:00:10 /usr/sbin/winbindd
root 897 894 0 Aug13 ? 00:00:09 /usr/sbin/winbindd
volumio 901 1 0 Aug13 ? 00:15:39 /usr/local/bin/node /volumio/index.js
root 915 1 0 Aug13 ? 00:02:12 /usr/sbin/smbd -D
root 929 894 0 Aug13 ? 00:00:03 /usr/sbin/winbindd
root 950 894 0 Aug13 ? 00:00:03 /usr/sbin/winbindd
root 961 915 0 Aug13 ? 00:00:12 /usr/sbin/smbd -D
volumio 1063 901 0 Aug13 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1069 1063 0 Aug13 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1070 1063 0 Aug13 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1075 1063 0 Aug13 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1180 1 0 Aug13 ? 00:00:02 /bin/streaming-daemon
root 2734 2 0 12:00 ? 00:00:00 [kworker/1:2H]
root 3509 2 0 12:35 ? 00:00:00 [kworker/3:0-mm_]
root 4536 2 0 13:21 ? 00:00:00 [kworker/2:1-mm_]
root 5339 2 0 13:57 ? 00:00:00 [kworker/0:2H]
root 6300 2 0 Sep17 ? 00:00:00 [kworker/u8:1-ev]
root 6509 2 0 14:48 ? 00:00:00 [kworker/1:1-eve]
root 7301 2 0 15:08 ? 00:00:00 [kworker/2:2-mm_]
root 7414 2 0 15:14 ? 00:00:00 [kworker/u8:0-ev]
root 7510 2 0 15:17 ? 00:00:00 [kworker/3:1-mm_]
root 7575 2 0 15:20 ? 00:00:00 [kworker/1:0-eve]
root 7595 2 0 15:20 ? 00:00:00 [kworker/0:1-eve]
root 7606 2 0 15:21 ? 00:00:00 [kworker/u8:2]
root 7683 2 0 15:23 ? 00:00:00 [kworker/3:2-eve]
shairpo+ 7787 1 2 15:25 ? 00:00:01 /usr/local/bin/shairport-sync --configfile=/tmp/shairport-sync.conf
root 7823 609 0 15:25 ? 00:00:00 sleep 60
root 7825 2 0 15:25 ? 00:00:00 [kworker/1:2-eve]
root 7827 2 0 15:26 ? 00:00:00 [kworker/0:0-eve]
volumio 7828 901 0 15:26 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'Spotify'
volumio 7829 7828 32 15:26 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js Spotify
volumio 7854 7829 0 15:26 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1
volumio 7855 7854 0 15:26 ? 00:00:00 ps -ef
root 24781 2 0 04:28 ? 00:00:00 [kworker/0:2-eve]
root 29429 2 0 07:52 ? 00:00:00 [kworker/3:2H]
root 29955 2 0 08:15 ? 00:00:00 [kworker/2:2H]
root 30987 1 0 Sep01 ? 00:00:01 dhcpcd eth0
volumio 31997 1 0 Sep01 ? 00:00:00 /bin/bash /data/plugins/music_service/volspotconnect2/startconnect.sh
volumio 31998 31997 0 Sep01 ? 00:10:45 ./vollibrespot -c volspotify.toml
# df -h ---------------
Filesystem Size Used Avail Use% Mounted on
/dev/mmcblk0p2 2.2G 1.2G 893M 58% /imgpart
/dev/loop0 349M 349M 0 100% /static
overlay 13G 171M 12G 2% /
devtmpfs 471M 0 471M 0% /dev
tmpfs 485M 0 485M 0% /dev/shm
tmpfs 485M 33M 453M 7% /run
tmpfs 5.0M 4.0K 5.0M 1% /run/lock
tmpfs 485M 0 485M 0% /sys/fs/cgroup
tmpfs 485M 13M 473M 3% /tmp
tmpfs 20M 16K 20M 1% /var/log
tmpfs 485M 0 485M 0% /var/spool/cups
tmpfs 485M 0 485M 0% /var/spool/cups/tmp
/dev/mmcblk0p1 61M 57M 3.4M 95% /boot
/dev/sda1 16G 933M 15G 7% /media/MEDIA
# mount ---------------
/dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime,stripe=1024)
/dev/loop0 on /static type squashfs (ro,relatime)
overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work)
sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime)
proc on /proc type proc (rw,relatime)
devtmpfs on /dev type devtmpfs (rw,nosuid,size=482120k,nr_inodes=120530,mode=755)
tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec)
devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000)
tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755)
tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k)
tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755)
cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,release_agent=/lib/systemd/systemd-cgroups-agent,name=systemd)
cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset)
cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct)
cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio)
cgroup on /sys/fs/cgroup/memory type cgroup (rw,nosuid,nodev,noexec,relatime,memory)
cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices)
cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer)
cgroup on /sys/fs/cgroup/net_cls type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls)
cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids)
systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=22,pgrp=1,timeout=300,minproto=5,maxproto=5,direct)
debugfs on /sys/kernel/debug type debugfs (rw,relatime)
mqueue on /dev/mqueue type mqueue (rw,relatime)
fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime)
configfs on /sys/kernel/config type configfs (rw,relatime)
tmpfs on /tmp type tmpfs (rw,noatime,mode=755)
tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4)
tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755)
tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755)
/dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro)
rpc_pipefs on /run/rpc_pipefs type rpc_pipefs (rw,relatime)
/dev/sda1 on /media/MEDIA type vfat (rw,nosuid,nodev,noatime,uid=1000,gid=1000,fmask=0000,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,showexec,utf8,errors=remount-ro,uhelper=udisks)
# sudo journalctl -p 7 ---------------
-- Logs begin at Sat 2020-08-29 13:48:51 UTC, end at Fri 2020-09-18 15:26:09 UTC. --
Aug 29 13:48:51 volumio volumio[901]: [SpotifyConnect] Sink acquired
Aug 29 13:48:51 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 13:48:51 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 13:48:51 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 13:48:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 13:48:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 13:48:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 13:48:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 13:49:01 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 13:49:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Aug 29 13:49:01 volumio volumio[901]: info: Listing playlists
Aug 29 13:49:01 volumio volumio[901]: info: CoreCommandRouter::volumioGetQueue
Aug 29 13:49:01 volumio volumio[901]: info: CoreStateMachine::getQueue
Aug 29 13:49:01 volumio volumio[901]: info: CorePlayQueue::getQueue
Aug 29 13:49:01 volumio volumio[901]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 29 13:49:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 29 13:49:01 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 13:49:03 volumio volumio[1123]: [Vollibrespot] : Event: SessionInactive { became_inactive_at: 1598708943555 }
Aug 29 13:49:03 volumio volumio[901]: [SpotifyConnect] Connect Session has ended
Aug 29 13:49:03 volumio volumio[901]: [SpotifyConnect] Relinquishing Volumio State
Aug 29 13:49:03 volumio volumio[901]: UNSET VOLATILE
Aug 29 13:49:03 volumio volumio[901]: [SpotifyConnect] unSetVolatile called
Aug 29 13:49:03 volumio volumio[901]: [SpotifyConnect] Relinquishing Volumio State to another service
Aug 29 13:49:03 volumio volumio[901]: [SpotifyConnect] Received stop
Aug 29 13:49:03 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 13:49:03 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 13:49:03 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 13:49:03 volumio volumio[901]: info: VolumeController:: Volume=34 Mute =false
Aug 29 13:49:03 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 13:49:03 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 13:49:03 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 13:49:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 13:49:03 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 13:49:03 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 13:49:03 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 13:49:03 volumio volumio[901]: info: VolumeController:: Volume=34 Mute =false
Aug 29 13:49:03 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 13:49:03 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 13:49:03 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 13:49:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 13:49:04 volumio volumio[1123]: [Vollibrespot] : Pause
Aug 29 13:49:04 volumio volumio[901]: [SpotifyConnect] Pause
Aug 29 13:49:04 volumio volumio[1123]: [Vollibrespot] : Event: SinkInactive
Aug 29 13:49:04 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 159280616236237041443801036414980113676, audio_type: Track } }
Aug 29 13:49:04 volumio volumio[901]: [SpotifyConnect] Sink released
Aug 29 13:49:04 volumio volumio[901]: [SpotifyConnect] Not pushing Pause { active: false, isStopping: true}
Aug 29 13:49:04 volumio volumio[901]: [SpotifyConnect] ResolvedStop in 0s 526.00ms
Aug 29 13:49:04 volumio volumio[901]: [SpotifyConnect] Device palyback is inactive
Aug 29 13:49:04 volumio volumio[901]: [SpotifyConnect] Device is paused
Aug 29 14:31:37 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 14:31:38 volumio volumio[901]: [SpotifyConnect] BQDiYOyrhjf6DZHWeNTxB8OjdJwCrfocbJAN36YnBO3uKIZqs5tX9YpzwcCNust69bxU0G3HnQ17Kqgf8g81z0zEUEv-xtIzpANPhM9FFInD2XiCC4XDGyoZ31vRzZzYZhVYyYITI3tDz2mh-l6XiWbRLCIkxb_SDA
Aug 29 14:31:38 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 14:31:38 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 14:31:38 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 14:31:38 volumio volumio[901]: is_active: false,
Aug 29 14:31:38 volumio volumio[901]: is_private_session: false,
Aug 29 14:31:38 volumio volumio[901]: is_restricted: false,
Aug 29 14:31:38 volumio volumio[901]: name: 'Volumio',
Aug 29 14:31:38 volumio volumio[901]: type: 'Speaker',
Aug 29 14:31:38 volumio volumio[901]: volume_percent: 34 },
Aug 29 14:31:38 volumio volumio[901]: [length]: 1 ] }
Aug 29 15:29:38 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 15:29:38 volumio volumio[901]: [SpotifyConnect] BQBgp3vzL6h9x-JMcz9pz-FGx_1E2eeNchq1aGYpDnA7MPYf0T8wBM9X40QiTJuMg7Q1ssZChH4BKecVtOqFNcKWe_4nBPl84ks4Zk414qC41cVh7_5kOhZKTQM8qG-s4qZt654_BDU8T16Q0syn4XO1RN59eNV6-A
Aug 29 15:29:38 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 15:29:38 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 15:29:38 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 15:29:38 volumio volumio[901]: is_active: false,
Aug 29 15:29:38 volumio volumio[901]: is_private_session: false,
Aug 29 15:29:38 volumio volumio[901]: is_restricted: false,
Aug 29 15:29:38 volumio volumio[901]: name: 'Volumio',
Aug 29 15:29:38 volumio volumio[901]: type: 'Speaker',
Aug 29 15:29:38 volumio volumio[901]: volume_percent: 34 },
Aug 29 15:29:38 volumio volumio[901]: [length]: 1 ] }
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : Fetching autoplay context uri
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : Event: SessionActive { became_active_at: 1598717310886 }
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : SessionActive!
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 145366386847230185534649057604287727449, audio_type: Track } }
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 145366386847230185534649057604287727449, audio_type: Track } }
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] A connect session has begun
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] Device palyback is active!
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:08:30 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] Currently active: mpd
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] Stopping currently active service
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::volumioStop
Aug 29 16:08:30 volumio volumio[901]: info: CoreStateMachine::stop
Aug 29 16:08:30 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 29 16:08:30 volumio volumio[901]: UNSET VOLATILE
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] Vollibrespot Active
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:08:30 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] Currently active: mpd
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] Setting Volatile state to Volspotconnect2
Aug 29 16:08:30 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:08:30 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:08:30 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:08:30 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:2ISApOKCrS01UHdjUWvEbT">
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : Resolving uri "spotify:station:playlist:2ISApOKCrS01UHdjUWvEbT"
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 29 16:08:30 volumio volumio[1123]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCA9bpzy4-GOrjeLby8wxYSvIQ4goTFwO6BLD_f0BLya6WByr2U2IZoqXZj1-Fix0cIHEvk0GBi-0CMk0j9Ni62cTK6DpQeOAYXYVSmRDcvlBbz6JfeEjtlsmgsmnOAlBKzQPibMPh4gyCfNveBvX4-KOtYedBWnA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
Aug 29 16:08:30 volumio volumio[901]: [SpotifyConnect] BQCA9bpzy4-GOrjeLby8wxYSvIQ4goTFwO6BLD_f0BLya6WByr2U2IZoqXZj1-Fix0cIHEvk0GBi-0CMk0j9Ni62cTK6DpQeOAYXYVSmRDcvlBbz6JfeEjtlsmgsmnOAlBKzQPibMPh4gyCfNveBvX4-KOtYedBWnA
Aug 29 16:08:31 volumio volumio[901]: [SpotifyConnect] Setting VLS device_id: c6e56cf6b68c07041768b06e2f29a439e7724b0e
Aug 29 16:08:31 volumio volumio[1123]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:2ISApOKCrS01UHdjUWvEbT">
Aug 29 16:08:31 volumio volumio[1123]: [Vollibrespot] : (211985 ms) loaded
Aug 29 16:08:31 volumio volumio[1123]: [Vollibrespot] : Event: SinkActive
Aug 29 16:08:31 volumio volumio[901]: [SpotifyConnect] Sink acquired
Aug 29 16:08:31 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:08:31 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 16:08:31 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 16:08:31 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:08:31 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:08:31 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:08:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:08:32 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21493 }
Aug 29 16:08:32 volumio volumio[901]: [SpotifyConnect] 32.79621576256962
Aug 29 16:08:32 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:32.79621576256962 Volumio: 33
Aug 29 16:08:32 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:08:32 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:08:32 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:08:32 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 20469 }
Aug 29 16:08:32 volumio volumio[901]: [SpotifyConnect] 31.233691920347905
Aug 29 16:08:32 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:31.233691920347905 Volumio: 31
Aug 29 16:08:32 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:08:32 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:08:32 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:08:36 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21493 }
Aug 29 16:08:36 volumio volumio[901]: [SpotifyConnect] 32.79621576256962
Aug 29 16:08:36 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:32.79621576256962 Volumio: 33
Aug 29 16:08:36 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:08:36 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:08:36 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:11:28 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 29 16:11:28 volumio volumio[1123]: [Vollibrespot] : (292026 ms) loaded
Aug 29 16:11:58 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 292203549985138711620031761870381465830, audio_type: Track } }
Aug 29 16:11:58 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 145366386847230185534649057604287727449, audio_type: Track }, track_id: SpotifyId { id: 292203549985138711620031761870381465830, audio_type: Track } }
Aug 29 16:11:58 volumio volumio[901]: [SpotifyConnect] Epoch
Aug 29 16:11:58 volumio volumio[901]: [SpotifyConnect] Pushing metadata
Aug 29 16:11:58 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:11:58 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 16:11:58 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 16:11:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:11:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:11:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:11:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:16:20 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 29 16:16:20 volumio volumio[1123]: [Vollibrespot] : (273592 ms) loaded
Aug 29 16:16:50 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 78119028726355439569038445337694465029, audio_type: Track } }
Aug 29 16:16:50 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 292203549985138711620031761870381465830, audio_type: Track }, track_id: SpotifyId { id: 78119028726355439569038445337694465029, audio_type: Track } }
Aug 29 16:16:50 volumio volumio[901]: [SpotifyConnect] Playa Paraíso
Aug 29 16:16:50 volumio volumio[901]: [SpotifyConnect] Pushing metadata
Aug 29 16:16:50 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:16:50 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 16:16:50 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 16:16:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:16:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:16:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:16:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:20:53 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 29 16:20:53 volumio volumio[1123]: [Vollibrespot] : (247320 ms) loaded
Aug 29 16:21:23 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 102675644022553368352671408217545173392, audio_type: Track } }
Aug 29 16:21:23 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 78119028726355439569038445337694465029, audio_type: Track }, track_id: SpotifyId { id: 102675644022553368352671408217545173392, audio_type: Track } }
Aug 29 16:21:23 volumio volumio[901]: [SpotifyConnect] Lie
Aug 29 16:21:23 volumio volumio[901]: [SpotifyConnect] Pushing metadata
Aug 29 16:21:23 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:21:23 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 16:21:23 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 16:21:23 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:21:23 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:21:23 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:21:23 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:25:01 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 29 16:25:01 volumio volumio[1123]: [Vollibrespot] : (167766 ms) loaded
Aug 29 16:25:30 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 3119174525081009069269319138189247746, audio_type: Track } }
Aug 29 16:25:30 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 102675644022553368352671408217545173392, audio_type: Track }, track_id: SpotifyId { id: 3119174525081009069269319138189247746, audio_type: Track } }
Aug 29 16:25:31 volumio volumio[901]: [SpotifyConnect] Free Form
Aug 29 16:25:31 volumio volumio[901]: [SpotifyConnect] Pushing metadata
Aug 29 16:25:31 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:25:31 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 16:25:31 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 16:25:31 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:25:31 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:25:31 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:25:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:27:48 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 29 16:27:48 volumio volumio[1123]: [Vollibrespot] : (222096 ms) loaded
Aug 29 16:28:18 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track } }
Aug 29 16:28:18 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 3119174525081009069269319138189247746, audio_type: Track }, track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track } }
Aug 29 16:28:18 volumio volumio[901]: [SpotifyConnect] Bloom
Aug 29 16:28:18 volumio volumio[901]: [SpotifyConnect] Pushing metadata
Aug 29 16:28:18 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:28:18 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 16:28:18 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 16:28:18 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:18 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:28:18 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:18 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:28:34 volumio volumio[1123]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track }, position_ms: 16053 }
Aug 29 16:28:34 volumio volumio[901]: [SpotifyConnect] pause
Aug 29 16:28:35 volumio volumio[901]: [SpotifyConnect] Bloom
Aug 29 16:28:35 volumio volumio[901]: [SpotifyConnect] Pushing metadata
Aug 29 16:28:35 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 16:28:35 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 29 16:28:35 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 29 16:28:35 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:35 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:28:35 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:35 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:28:35 volumio volumio[1123]: [Vollibrespot] : Event: SinkInactive
Aug 29 16:28:35 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track } }
Aug 29 16:28:35 volumio volumio[901]: [SpotifyConnect] Sink released
Aug 29 16:28:35 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:35 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:28:35 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:35 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:28:35 volumio volumio[901]: [SpotifyConnect] Device palyback is inactive
Aug 29 16:28:35 volumio volumio[901]: [SpotifyConnect] Device is paused
Aug 29 16:35:31 volumio volumio[1123]: [Vollibrespot] : Event: SessionInactive { became_inactive_at: 1598718931214 }
Aug 29 16:35:31 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track } }
Aug 29 16:35:31 volumio volumio[901]: [SpotifyConnect] Connect Session has ended
Aug 29 16:35:31 volumio volumio[901]: [SpotifyConnect] Relinquishing Volumio State
Aug 29 16:35:31 volumio volumio[901]: UNSET VOLATILE
Aug 29 16:35:31 volumio volumio[901]: [SpotifyConnect] unSetVolatile called
Aug 29 16:35:31 volumio volumio[901]: [SpotifyConnect] Relinquishing Volumio State to another service
Aug 29 16:35:31 volumio volumio[901]: [SpotifyConnect] Received stop
Aug 29 16:35:31 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 16:35:31 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 16:35:31 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 16:35:31 volumio volumio[901]: [SpotifyConnect] Device palyback is inactive
Aug 29 16:35:31 volumio volumio[901]: [SpotifyConnect] Device is paused
Aug 29 16:35:31 volumio volumio[901]: info: VolumeController:: Volume=32 Mute =false
Aug 29 16:35:31 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:35:31 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 16:35:31 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:35:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:35:31 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 16:35:31 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 16:35:31 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 16:35:31 volumio volumio[901]: info: VolumeController:: Volume=32 Mute =false
Aug 29 16:35:31 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 16:35:31 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 16:35:31 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 16:35:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:35:31 volumio volumio[1123]: [Vollibrespot] : Pause
Aug 29 16:35:31 volumio volumio[901]: [SpotifyConnect] Pause
Aug 29 17:06:30 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 17:06:31 volumio volumio[901]: [SpotifyConnect] BQBKDA7m01vWwJk1y0Up4omkNtvFYj_8yZIVJmuMOJbW1598cp-45nLe7dCvJSdoB-IRZm61tdBr5vmriOg34RKNeclxJLLR6fa25Ewa0VGOW62Rl2Gh3OUYFLlK-I0vAY6TssXeJgIg14sJesbbGEOOV5R_djEJKw
Aug 29 17:06:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 17:06:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 17:06:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 17:06:31 volumio volumio[901]: is_active: false,
Aug 29 17:06:31 volumio volumio[901]: is_private_session: false,
Aug 29 17:06:31 volumio volumio[901]: is_restricted: false,
Aug 29 17:06:31 volumio volumio[901]: name: 'Volumio',
Aug 29 17:06:31 volumio volumio[901]: type: 'Speaker',
Aug 29 17:06:31 volumio volumio[901]: volume_percent: 32 },
Aug 29 17:06:31 volumio volumio[901]: [length]: 1 ] }
Aug 29 18:04:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 18:04:31 volumio volumio[901]: [SpotifyConnect] BQDG9qUmkp-02XxUT0Qzn7R1cYS4S4qQIC_63v0XgAyXQ1MzRjmZUxlGh3scCxLdXN0m8e8X4jC2z9-f7vfFbZusXMTiyRlHkbq465qwuxdBR9MbnO8kXNMdtOtztvLkErtQOHf4Z09M1-7DcZQnmtrfEqlPxcmN_w
Aug 29 18:04:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 18:04:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 18:04:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 18:04:31 volumio volumio[901]: is_active: false,
Aug 29 18:04:31 volumio volumio[901]: is_private_session: false,
Aug 29 18:04:31 volumio volumio[901]: is_restricted: false,
Aug 29 18:04:31 volumio volumio[901]: name: 'Volumio',
Aug 29 18:04:31 volumio volumio[901]: type: 'Speaker',
Aug 29 18:04:31 volumio volumio[901]: volume_percent: 32 },
Aug 29 18:04:31 volumio volumio[901]: [length]: 1 ] }
Aug 29 19:02:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 19:02:31 volumio volumio[901]: [SpotifyConnect] BQADTO3nl90ouZ2lJGR-MjNaP-I-2OLGxTY9J1oExJrzoNT5SH_MPaaZKc4PasGc3KndnUi4aR-tj3FkoMZ1KiRHz6OSta-R5jW81JqB42M-cVNryo-adTOvjOM2-8t7AxNZ5oJbh_gdUjFS0KGOgPAFCzhz8NClXg
Aug 29 19:02:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 19:02:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 19:02:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 19:02:31 volumio volumio[901]: is_active: false,
Aug 29 19:02:31 volumio volumio[901]: is_private_session: false,
Aug 29 19:02:31 volumio volumio[901]: is_restricted: false,
Aug 29 19:02:31 volumio volumio[901]: name: 'Volumio',
Aug 29 19:02:31 volumio volumio[901]: type: 'Speaker',
Aug 29 19:02:31 volumio volumio[901]: volume_percent: 32 },
Aug 29 19:02:31 volumio volumio[901]: [length]: 1 ] }
Aug 29 20:00:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 20:00:31 volumio volumio[901]: [SpotifyConnect] BQA-qM7l1Vo-QxIRc8tGcVatBc6iggSuebnpqj4sOldGvVf-aiJu2fkw7s1nY0aCrIs1muOMlkAzMu06JJOYfz45rAF30JFkqdfbBQc0T9jSkRsZz87XnCD-zrebXZB7p6ug1-AwVW6Ufv24W1GGJXq6Vvj18_AkfQ
Aug 29 20:00:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 20:00:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 20:00:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 20:00:31 volumio volumio[901]: is_active: false,
Aug 29 20:00:31 volumio volumio[901]: is_private_session: false,
Aug 29 20:00:31 volumio volumio[901]: is_restricted: false,
Aug 29 20:00:31 volumio volumio[901]: name: 'Volumio',
Aug 29 20:00:31 volumio volumio[901]: type: 'Speaker',
Aug 29 20:00:31 volumio volumio[901]: volume_percent: 32 },
Aug 29 20:00:31 volumio volumio[901]: [length]: 1 ] }
Aug 29 20:35:27 volumio systemd[1]: Starting Cleanup of Temporary Directories...
Aug 29 20:35:27 volumio systemd[1]: Started Cleanup of Temporary Directories.
Aug 29 20:58:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 20:58:31 volumio volumio[901]: [SpotifyConnect] BQC6XEptGzY5Jpbc_wlK3TABE9vF5ZMA3-GzblXMyFK6LTLgzTCVubpGsgqeOeDTYuSSwXNoOMIgwSObDgjgOVuH54K8hVzrLDM1R9p6t2X0-IDKkca7o5Pcv9iPY8cneslVmuOu2fOvRSkY6NzREDBq3RicPmc4pA
Aug 29 20:58:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 20:58:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 20:58:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 20:58:31 volumio volumio[901]: is_active: false,
Aug 29 20:58:31 volumio volumio[901]: is_private_session: false,
Aug 29 20:58:31 volumio volumio[901]: is_restricted: false,
Aug 29 20:58:31 volumio volumio[901]: name: 'Volumio',
Aug 29 20:58:31 volumio volumio[901]: type: 'Speaker',
Aug 29 20:58:31 volumio volumio[901]: volume_percent: 32 },
Aug 29 20:58:31 volumio volumio[901]: [length]: 1 ] }
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:01 volumio shairport-sync[26120]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory
Aug 29 21:02:01 volumio shairport-sync[26120]: %% TToottaall %% RReecceeiivvede d% %X fXefredr d A vAervaegrea gSep eSepde e d Ti mTei m e T i mTei m e T i mTei m eC u rCruernrt
Aug 29 21:02:01 volumio shairport-sync[26120]: en t
Aug 29 21:02:01 volumio volumio[901]: info:
Aug 29 21:02:01 volumio volumio[901]: ---------------------------- Client requests AirplayActive
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive
Aug 29 21:02:01 volumio volumio[901]: info:
Aug 29 21:02:01 volumio volumio[901]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Aug 29 21:02:01 volumio volumio[901]: info: Airplay playback start
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::volumioStop
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::stop
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 29 21:02:01 volumio volumio[901]: UNSET VOLATILE
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 29 21:02:01 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::volumioStop
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::stop
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 29 21:02:01 volumio volumio[901]: UNSET VOLATILE
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 29 21:02:01 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::volumioStop
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::stop
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 29 21:02:01 volumio volumio[901]: UNSET VOLATILE
Aug 29 21:02:01 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:01 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:01 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:01 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:01 volumio shairport-sync[26120]: D l oa dD l oUapdl o aUdp l oa dT o ta lT o t aSlp e n tS p e Lnetf t S p eLeedf
Aug 29 21:02:01 volumio shairport-sync[26120]: [9B blob data]
Aug 29 21:02:01 volumio shairport-sync[26120]: [234B blob data]
Aug 29 21:02:01 volumio shairport-sync[26120]: [136B blob data]
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:02 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:02 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:02 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:02 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:02 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:02 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:02 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Aug 29 21:02:02 volumio volumio[901]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:02 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:07 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:07 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:07 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:07 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:07 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:07 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:07 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:07 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:14 volumio shairport-sync[26120]: {"time":1598734921942,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 21:02:14 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 21:02:14 volumio volumio[901]: info:
Aug 29 21:02:14 volumio volumio[901]: ---------------------------- Client requests Stop Airplay Playback
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Aug 29 21:02:14 volumio volumio[901]: info: Airplay playback stop
Aug 29 21:02:14 volumio volumio[901]: info: Airplay Playback Stopped, clearing state
Aug 29 21:02:14 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 21:02:14 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 21:02:14 volumio shairport-sync[26120]: [155B blob data]
Aug 29 21:02:14 volumio volumio[901]: info: VolumeController:: Volume=32 Mute =false
Aug 29 21:02:14 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:14 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 21:02:14 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 21:02:14 volumio volumio[901]: info: VolumeController:: Volume=32 Mute =false
Aug 29 21:02:14 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:14 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:15 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 21:02:15 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 21:02:15 volumio shairport-sync[26120]: {"time":1598734934623,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 21:02:15 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:15 volumio volumio[901]: info:
Aug 29 21:02:15 volumio volumio[901]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Aug 29 21:02:15 volumio volumio[901]: info: Airplay playback start
Aug 29 21:02:15 volumio shairport-sync[26120]: [155B blob data]
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:25 volumio shairport-sync[26120]: {"time":1598734935175,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 21:02:25 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 21:02:25 volumio volumio[901]: info:
Aug 29 21:02:25 volumio volumio[901]: ---------------------------- Client requests Stop Airplay Playback
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Aug 29 21:02:25 volumio volumio[901]: info: Airplay playback stop
Aug 29 21:02:25 volumio volumio[901]: info: Airplay Playback Stopped, clearing state
Aug 29 21:02:25 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 21:02:25 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 21:02:25 volumio shairport-sync[26120]: [155B blob data]
Aug 29 21:02:25 volumio volumio[901]: info: VolumeController:: Volume=32 Mute =false
Aug 29 21:02:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:25 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 21:02:25 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 21:02:25 volumio volumio[901]: info: VolumeController:: Volume=32 Mute =false
Aug 29 21:02:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:35 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:35 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:35 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:35 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:35 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:02:35 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:02:35 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:02:35 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:02:35 volumio shairport-sync[26120]: {"time":1598734945517,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 21:02:35 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 21:02:35 volumio volumio[901]: info:
Aug 29 21:02:35 volumio volumio[901]: ---------------------------- Client requests AirplayInactive
Aug 29 21:02:35 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive
Aug 29 21:02:35 volumio shairport-sync[26120]: [155B blob data]
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 21:52:19 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio shairport-sync[26120]: {"time":1598734955514,"response":"airplayInactive Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 21:52:19 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 21:52:19 volumio volumio[901]: info:
Aug 29 21:52:19 volumio volumio[901]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Aug 29 21:52:19 volumio shairport-sync[26120]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 21:52:19 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Aug 29 21:52:19 volumio volumio[901]: info: Airplay playback start
Aug 29 21:52:19 volumio shairport-sync[26120]: [155B blob data]
Aug 29 21:52:19 volumio volumio[901]: info:
Aug 29 21:52:19 volumio volumio[901]: ---------------------------- Client requests AirplayActive
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive
Aug 29 21:52:19 volumio shairport-sync[26120]: [143B blob data]
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:22 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:22 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:22 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:22 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:22 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:22 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:23 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:24 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:39 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 21:52:41 volumio sudo[12151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 29 21:52:41 volumio sudo[12151]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 29 21:52:41 volumio sudo[12154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 29 21:52:41 volumio sudo[12151]: pam_unix(sudo:session): session closed for user root
Aug 29 21:52:41 volumio sudo[12154]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 29 21:52:41 volumio sudo[12154]: pam_unix(sudo:session): session closed for user root
Aug 29 21:52:41 volumio sudo[12169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 29 21:52:41 volumio sudo[12169]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 29 21:52:41 volumio sudo[12169]: pam_unix(sudo:session): session closed for user root
Aug 29 21:52:41 volumio sudo[12172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 29 21:52:41 volumio sudo[12172]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 29 21:52:41 volumio sudo[12172]: pam_unix(sudo:session): session closed for user root
Aug 29 21:52:41 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 29 21:52:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Aug 29 21:52:41 volumio volumio[901]: info: Listing playlists
Aug 29 21:52:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Aug 29 21:52:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Aug 29 21:52:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Aug 29 21:52:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 29 21:52:44 volumio volumio[901]: info: VolumeController::SetAlsaVolume40
Aug 29 21:52:44 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:44 volumio volumio[901]: info: VolumeController::SetAlsaVolume45
Aug 29 21:52:44 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:47 volumio volumio[901]: info: VolumeController::SetAlsaVolume55
Aug 29 21:52:47 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:47 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:47 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:52:47 volumio volumio[901]: info: VolumeController::SetAlsaVolume60
Aug 29 21:52:47 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 21:52:47 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 21:52:47 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 21:56:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 21:56:31 volumio volumio[901]: [SpotifyConnect] BQCuwDl4cs_CA9Jid-AgEKhaIXC6jSGsCIvGELLLrST2hlNRyN02HWDidvuyhUBEVr3cJCZ5ABPia-bemVztfnt_jCC-b5MK4X7yjgc7cjPLwKQMX4ozincKhVBD7T1ilIlm9kQaa7XcHFs85DGGa_qsfKCpcjd1fg
Aug 29 21:56:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 21:56:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 21:56:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 21:56:31 volumio volumio[901]: is_active: false,
Aug 29 21:56:31 volumio volumio[901]: is_private_session: false,
Aug 29 21:56:31 volumio volumio[901]: is_restricted: false,
Aug 29 21:56:31 volumio volumio[901]: name: 'Volumio',
Aug 29 21:56:31 volumio volumio[901]: type: 'Speaker',
Aug 29 21:56:31 volumio volumio[901]: volume_percent: 32 },
Aug 29 21:56:31 volumio volumio[901]: [length]: 1 ] }
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:00 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:41 volumio shairport-sync[26120]: {"time":1598737939069,"response":"airplayActive Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:15:41 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:15:41 volumio volumio[901]: info:
Aug 29 22:15:41 volumio volumio[901]: ---------------------------- Client requests Stop Airplay Playback
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Aug 29 22:15:41 volumio volumio[901]: info: Airplay playback stop
Aug 29 22:15:41 volumio volumio[901]: info: Airplay Playback Stopped, clearing state
Aug 29 22:15:41 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 22:15:41 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:15:41 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:15:41 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:15:41 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:41 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 22:15:41 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:15:41 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:15:41 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:51 volumio shairport-sync[26120]: {"time":1598739341187,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:15:51 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:15:51 volumio volumio[901]: info:
Aug 29 22:15:51 volumio volumio[901]: ---------------------------- Client requests AirplayInactive
Aug 29 22:15:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive
Aug 29 22:15:51 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:57 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:15:57 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:57 volumio shairport-sync[26120]: {"time":1598739351189,"response":"airplayInactive Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:15:57 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:15:57 volumio volumio[901]: info:
Aug 29 22:15:57 volumio volumio[901]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Aug 29 22:15:57 volumio volumio[901]: info: Airplay playback start
Aug 29 22:15:57 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:15:57 volumio shairport-sync[26120]: {"time":1598739357500,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:15:57 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:15:57 volumio volumio[901]: info:
Aug 29 22:15:57 volumio volumio[901]: ---------------------------- Client requests AirplayActive
Aug 29 22:15:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive
Aug 29 22:15:57 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:15:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:19 volumio shairport-sync[26120]: {"time":1598739357511,"response":"airplayActive Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:16:19 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:16:19 volumio volumio[901]: info:
Aug 29 22:16:19 volumio volumio[901]: ---------------------------- Client requests Stop Airplay Playback
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Aug 29 22:16:19 volumio volumio[901]: info: Airplay playback stop
Aug 29 22:16:19 volumio volumio[901]: info: Airplay Playback Stopped, clearing state
Aug 29 22:16:19 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 22:16:19 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:16:19 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:16:19 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:16:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:19 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 22:16:19 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:16:19 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:16:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:25 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:16:25 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:25 volumio shairport-sync[26120]: {"time":1598739379569,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:16:25 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:16:25 volumio volumio[901]: info:
Aug 29 22:16:25 volumio volumio[901]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Aug 29 22:16:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Aug 29 22:16:25 volumio volumio[901]: info: Airplay playback start
Aug 29 22:16:25 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:16:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:25 volumio shairport-sync[26120]: {"time":1598739385249,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:17:25 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:17:25 volumio volumio[901]: info:
Aug 29 22:17:25 volumio volumio[901]: ---------------------------- Client requests Stop Airplay Playback
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Aug 29 22:17:25 volumio volumio[901]: info: Airplay playback stop
Aug 29 22:17:25 volumio volumio[901]: info: Airplay Playback Stopped, clearing state
Aug 29 22:17:25 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 22:17:25 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:17:25 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:17:25 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:17:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:25 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 22:17:25 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:17:25 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:17:25 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:25 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:35 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:35 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:35 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:35 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:35 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:35 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:35 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:35 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:35 volumio shairport-sync[26120]: {"time":1598739445111,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:17:35 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:17:35 volumio volumio[901]: info:
Aug 29 22:17:35 volumio volumio[901]: ---------------------------- Client requests AirplayInactive
Aug 29 22:17:35 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive
Aug 29 22:17:35 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:17:39 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio shairport-sync[26120]: {"time":1598739455110,"response":"airplayInactive Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:17:39 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio shairport-sync[26120]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:17:39 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:17:39 volumio volumio[901]: info:
Aug 29 22:17:39 volumio volumio[901]: ---------------------------- Client requests AirplayActive
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive
Aug 29 22:17:39 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:17:39 volumio volumio[901]: info:
Aug 29 22:17:39 volumio volumio[901]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Aug 29 22:17:39 volumio volumio[901]: info: Airplay playback start
Aug 29 22:17:39 volumio shairport-sync[26120]: [136B blob data]
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:17:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:19:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:22:38 volumio shairport-sync[26120]: {"time":1598739459495,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:22:38 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:22:38 volumio volumio[901]: info:
Aug 29 22:22:38 volumio volumio[901]: ---------------------------- Client requests Stop Airplay Playback
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Aug 29 22:22:38 volumio volumio[901]: info: Airplay playback stop
Aug 29 22:22:38 volumio volumio[901]: info: Airplay Playback Stopped, clearing state
Aug 29 22:22:38 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 22:22:38 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:22:38 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:22:38 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:22:38 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:22:38 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 22:22:38 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:22:38 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:22:38 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:22:38 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:22:48 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:22:48 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:22:48 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:22:48 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:22:48 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:22:48 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:22:48 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:22:48 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:22:48 volumio shairport-sync[26120]: {"time":1598739758805,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:22:48 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:22:48 volumio volumio[901]: info:
Aug 29 22:22:48 volumio volumio[901]: ---------------------------- Client requests AirplayInactive
Aug 29 22:22:48 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive
Aug 29 22:22:48 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:23:04 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio shairport-sync[26120]: {"time":1598739768806,"response":"airplayInactive Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:23:04 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:23:04 volumio volumio[901]: info:
Aug 29 22:23:04 volumio volumio[901]: ---------------------------- Client requests AirplayActive
Aug 29 22:23:04 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive
Aug 29 22:23:04 volumio shairport-sync[26120]: {"time":1598739784087,"response":"airplayActive Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:23:04 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:23:04 volumio volumio[901]: info:
Aug 29 22:23:04 volumio volumio[901]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Aug 29 22:23:04 volumio volumio[901]: info: Airplay playback start
Aug 29 22:23:04 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:23:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:25:51 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:12 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:26:12 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:26:12 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:26:12 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:12 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:26:12 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:26:12 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:26:12 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:19 volumio shairport-sync[26120]: {"time":1598739784104,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:26:19 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:26:19 volumio volumio[901]: info:
Aug 29 22:26:19 volumio volumio[901]: ---------------------------- Client requests Stop Airplay Playback
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Aug 29 22:26:19 volumio volumio[901]: info: Airplay playback stop
Aug 29 22:26:19 volumio volumio[901]: info: Airplay Playback Stopped, clearing state
Aug 29 22:26:19 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 22:26:19 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:26:19 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:26:19 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:26:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:19 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 22:26:19 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:26:19 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:26:19 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:26:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:29 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:26:29 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:26:29 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:26:29 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:29 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:26:29 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:26:29 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:26:29 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:26:29 volumio shairport-sync[26120]: {"time":1598739979259,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:26:29 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:26:29 volumio volumio[901]: info:
Aug 29 22:26:29 volumio volumio[901]: ---------------------------- Client requests AirplayInactive
Aug 29 22:26:29 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive
Aug 29 22:26:29 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:56 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:29:56 volumio volumio[901]: info: Airplay started streaming, receiving metadatas
Aug 29 22:29:56 volumio shairport-sync[26120]: {"time":1598739989260,"response":"airplayInactive Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:29:56 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:29:56 volumio shairport-sync[26120]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:29:56 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:29:56 volumio volumio[901]: info:
Aug 29 22:29:56 volumio volumio[901]: ---------------------------- Client requests AirplayActive
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive
Aug 29 22:29:56 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:56 volumio volumio[901]: info:
Aug 29 22:29:56 volumio volumio[901]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Aug 29 22:29:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Aug 29 22:29:56 volumio volumio[901]: info: Airplay playback start
Aug 29 22:29:56 volumio shairport-sync[26120]: [136B blob data]
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:57 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:29:58 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:32:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:32:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:32:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:40 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:32:40 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:32:40 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:32:40 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:45 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:32:45 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:32:45 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:32:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:45 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:32:45 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:32:45 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:32:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:45 volumio shairport-sync[26120]: {"time":1598740196951,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:32:45 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:32:45 volumio volumio[901]: info:
Aug 29 22:32:45 volumio volumio[901]: ---------------------------- Client requests Stop Airplay Playback
Aug 29 22:32:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Aug 29 22:32:45 volumio volumio[901]: info: Airplay playback stop
Aug 29 22:32:45 volumio volumio[901]: info: Airplay Playback Stopped, clearing state
Aug 29 22:32:45 volumio volumio[901]: info: CoreStateMachine::resetVolumioState
Aug 29 22:32:45 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume
Aug 29 22:32:45 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:32:45 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:32:46 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:32:46 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:32:46 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:32:46 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:46 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Aug 29 22:32:46 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Aug 29 22:32:46 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume
Aug 29 22:32:46 volumio volumio[901]: info: VolumeController:: Volume=60 Mute =false
Aug 29 22:32:46 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:32:46 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:32:46 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:55 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:32:55 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:32:55 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:32:55 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:55 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 29 22:32:55 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 29 22:32:55 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 29 22:32:55 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 22:32:55 volumio shairport-sync[26120]: {"time":1598740365948,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
Aug 29 22:32:55 volumio shairport-sync[26120]: Dload Upload Total Spent Left Speed
Aug 29 22:32:55 volumio volumio[901]: info:
Aug 29 22:32:55 volumio volumio[901]: ---------------------------- Client requests AirplayInactive
Aug 29 22:32:55 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive
Aug 29 22:32:55 volumio shairport-sync[26120]: [155B blob data]
Aug 29 22:54:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 22:54:31 volumio volumio[901]: [SpotifyConnect] BQCCV7_PYexfZfz_EIjZb4FtyrozfsI1DdA5wE7NZPpKr4rsABsncR6x6CBXSsJNdr5u9Sm3Qss7gTi3-5-5yvTMD-Na5Ncsl04RWbjt6qevpJacqqihH9n8WQz01qanoX0xhBIAyU9SyCZhDpQICIRf3UL6-VYFtw
Aug 29 22:54:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 22:54:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 22:54:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 22:54:31 volumio volumio[901]: is_active: false,
Aug 29 22:54:31 volumio volumio[901]: is_private_session: false,
Aug 29 22:54:31 volumio volumio[901]: is_restricted: false,
Aug 29 22:54:31 volumio volumio[901]: name: 'Volumio',
Aug 29 22:54:31 volumio volumio[901]: type: 'Speaker',
Aug 29 22:54:31 volumio volumio[901]: volume_percent: 32 },
Aug 29 22:54:31 volumio volumio[901]: [length]: 1 ] }
Aug 29 23:52:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 29 23:52:31 volumio volumio[901]: [SpotifyConnect] BQAyIJucmIjFsgNwOl08ErnUIjixmXwD2DOi0_mRGfT0xXj-eYVeteCtGCSD4g-Z6KGTy-NTAefyYF7sg1ksZ-v8iUAWquhN8aO1_qC7FcIl6NjNBhR3hC-yMOQBjdcz8mv2kTrcVb8fFsLit8YRQYq-lit2rOGjfw
Aug 29 23:52:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 29 23:52:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 29 23:52:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 29 23:52:31 volumio volumio[901]: is_active: false,
Aug 29 23:52:31 volumio volumio[901]: is_private_session: false,
Aug 29 23:52:31 volumio volumio[901]: is_restricted: false,
Aug 29 23:52:31 volumio volumio[901]: name: 'Volumio',
Aug 29 23:52:31 volumio volumio[901]: type: 'Speaker',
Aug 29 23:52:31 volumio volumio[901]: volume_percent: 32 },
Aug 29 23:52:31 volumio volumio[901]: [length]: 1 ] }
Aug 30 00:50:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 00:50:31 volumio volumio[901]: [SpotifyConnect] BQBlturyuNTpLCx8o7DiL0Oc8qshFiWw33UTsivFiaSsbr6v1Zgw4mlP5ZWCavjaRMvjDJCWdoU8Ja4uBtltCaDcshPOMIhmucZ_yKIO2qi51tHK5e5mTxzgy8eZsb5UvTLwq2No77PecznLfohRRQmy6dSJF1sD0Q
Aug 30 00:50:31 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 30 00:50:31 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 30 00:50:31 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 30 00:50:31 volumio volumio[901]: is_active: false,
Aug 30 00:50:31 volumio volumio[901]: is_private_session: false,
Aug 30 00:50:31 volumio volumio[901]: is_restricted: false,
Aug 30 00:50:31 volumio volumio[901]: name: 'Volumio',
Aug 30 00:50:31 volumio volumio[901]: type: 'Speaker',
Aug 30 00:50:31 volumio volumio[901]: volume_percent: 32 },
Aug 30 00:50:31 volumio volumio[901]: [length]: 1 ] }
Aug 30 01:48:31 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 01:48:31 volumio volumio[901]: [SpotifyConnect] BQAlJ68dT9na6_FJtaiaAIQm9FCtg2iJsTHuO6PMy22fXfsOZQRWlWBYtMIjXWqBbDsxCnSTAtjS6SvT32aWZDF-NesAKv0zP-IYvrfFmzbv2XJJ6CqhRhHh0tQ5DISiNSHbRZmNH63taQou7AgmT6Hg0H7VapWFOA
Aug 30 01:48:32 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 30 01:48:32 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 30 01:48:32 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 30 01:48:32 volumio volumio[901]: is_active: false,
Aug 30 01:48:32 volumio volumio[901]: is_private_session: false,
Aug 30 01:48:32 volumio volumio[901]: is_restricted: false,
Aug 30 01:48:32 volumio volumio[901]: name: 'Volumio',
Aug 30 01:48:32 volumio volumio[901]: type: 'Speaker',
Aug 30 01:48:32 volumio volumio[901]: volume_percent: 32 },
Aug 30 01:48:32 volumio volumio[901]: [length]: 1 ] }
Aug 30 02:46:32 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 02:46:32 volumio volumio[901]: [SpotifyConnect] BQCbNIV2zLv2gV6xGzdK6REh1vbLcTlr-0A2TmpbTOelQ9U56p45Fz9-9ttFA1cu2YbCEZpa8KgdW7fdT80GkX6NbvjDGGsN9LfecuaWlD2X3o63yL7goSDm4TY-EPPaauqX0NETMOC9_iDVOMehr6V31UIKYmBPfg
Aug 30 02:46:32 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 30 02:46:32 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 30 02:46:32 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 30 02:46:32 volumio volumio[901]: is_active: false,
Aug 30 02:46:32 volumio volumio[901]: is_private_session: false,
Aug 30 02:46:32 volumio volumio[901]: is_restricted: false,
Aug 30 02:46:32 volumio volumio[901]: name: 'Volumio',
Aug 30 02:46:32 volumio volumio[901]: type: 'Speaker',
Aug 30 02:46:32 volumio volumio[901]: volume_percent: 32 },
Aug 30 02:46:32 volumio volumio[901]: [length]: 1 ] }
Aug 30 03:44:32 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 03:44:32 volumio volumio[901]: [SpotifyConnect] BQBKqqLXOxukC-l18kVgKnWW3MgMjojXlsYfzr3H2wNjXqfGoTAe4SSm-jRFqOnhb4qrq3gh8_IbMdpwO-vCWaTZpZDFVX9F4IlWaqWycGjhn4_AH_pso8-LAwOUXmamlJ5mCzLnfFdx1W3ZfjDtsKyYdT7vcZi2Bw
Aug 30 03:44:32 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 30 03:44:32 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 30 03:44:32 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 30 03:44:32 volumio volumio[901]: is_active: false,
Aug 30 03:44:32 volumio volumio[901]: is_private_session: false,
Aug 30 03:44:32 volumio volumio[901]: is_restricted: false,
Aug 30 03:44:32 volumio volumio[901]: name: 'Volumio',
Aug 30 03:44:32 volumio volumio[901]: type: 'Speaker',
Aug 30 03:44:32 volumio volumio[901]: volume_percent: 32 },
Aug 30 03:44:32 volumio volumio[901]: [length]: 1 ] }
Aug 30 04:42:32 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 04:42:32 volumio volumio[901]: [SpotifyConnect] BQDA8PULJ2xusRuSb_wy1e9yDJVS6XttZYAuONleqiKuImSniEclwpNKzTpVr2XH5cwd5bsnF8-7-H-ZYA4KXOjQkt6Gwfd28MOjzp3BsT4X5_QoAvk2ijOLoJ2O3Oa_WjiRr0yl4On0CfI_1UmaGY_pFmipmgbbcw
Aug 30 04:42:32 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 30 04:42:32 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 30 04:42:32 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 30 04:42:32 volumio volumio[901]: is_active: false,
Aug 30 04:42:32 volumio volumio[901]: is_private_session: false,
Aug 30 04:42:32 volumio volumio[901]: is_restricted: false,
Aug 30 04:42:32 volumio volumio[901]: name: 'Volumio',
Aug 30 04:42:32 volumio volumio[901]: type: 'Speaker',
Aug 30 04:42:32 volumio volumio[901]: volume_percent: 32 },
Aug 30 04:42:32 volumio volumio[901]: [length]: 1 ] }
Aug 30 05:40:32 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 05:40:32 volumio volumio[901]: [SpotifyConnect] BQDzGaG4xOIsuf11fUItcpdUwCAyKjKN9UWLkd4cU05-VsZp6_qvl0wXjiAgYPUaAquhp4xWnlkO6pgfOnsKLDr6GpeplSy_nYuMxSDI6wdNb8kUmyJuDOSkC-DfZmmYlGQbvkzCXf9qSWcuoUfjHUXg8ENEN9QauQ
Aug 30 05:40:32 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 30 05:40:32 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 30 05:40:32 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 30 05:40:32 volumio volumio[901]: is_active: false,
Aug 30 05:40:32 volumio volumio[901]: is_private_session: false,
Aug 30 05:40:32 volumio volumio[901]: is_restricted: false,
Aug 30 05:40:32 volumio volumio[901]: name: 'Volumio',
Aug 30 05:40:32 volumio volumio[901]: type: 'Speaker',
Aug 30 05:40:32 volumio volumio[901]: volume_percent: 32 },
Aug 30 05:40:32 volumio volumio[901]: [length]: 1 ] }
Aug 30 06:38:32 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 06:38:32 volumio volumio[901]: [SpotifyConnect] BQCMTYcAzmAt2LvGyMoTijf00NwpHFfu6D2cpdq6Y4tg8BO-mv3FUMgbdFD-YOQiwspaYWSahpma9NqAhNy4CSNEaVKU-bLKmkEe8_R_MuCcgZdnXNOd-hm2NDPSgJZitM5iaMMCBsfcvlqLEHDalXIURF-jkuAwiw
Aug 30 06:38:32 volumio volumio[901]: [SpotifyConnect] No active spotify devices found
Aug 30 06:38:32 volumio volumio[901]: [SpotifyConnect] Devices: { devices:
Aug 30 06:38:32 volumio volumio[901]: [ { id: 'c6e56cf6b68c07041768b06e2f29a439e7724b0e',
Aug 30 06:38:32 volumio volumio[901]: is_active: false,
Aug 30 06:38:32 volumio volumio[901]: is_private_session: false,
Aug 30 06:38:32 volumio volumio[901]: is_restricted: false,
Aug 30 06:38:32 volumio volumio[901]: name: 'Volumio',
Aug 30 06:38:32 volumio volumio[901]: type: 'Speaker',
Aug 30 06:38:32 volumio volumio[901]: volume_percent: 32 },
Aug 30 06:38:32 volumio volumio[901]: [length]: 1 ] }
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Fetching autoplay context uri
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Event: SessionActive { became_active_at: 1598771639058 }
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : SessionActive!
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] A connect session has begun
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track } }
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track } }
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Device palyback is active!
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Currently active: airplay_emulation
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Stopping currently active service
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::volumioStop
Aug 30 07:13:59 volumio volumio[901]: info: CoreStateMachine::stop
Aug 30 07:13:59 volumio volumio[901]: info: CoreStateMachine::serviceStop
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::serviceStop
Aug 30 07:13:59 volumio volumio[901]: info: Airplay Stop
Aug 30 07:13:59 volumio volumio[901]: info: Stopping Airplay Playback and sending pause command to client via USR2
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:2ISApOKCrS01UHdjUWvEbT">
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Resolving uri "spotify:station:playlist:2ISApOKCrS01UHdjUWvEbT"
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQA_J0rDyeJjTXmeODQ9yTfY8X16qnsl6vMouYs9TefqIif5MVlLIufIWRFrnvwi7bKK3ZSktAgy_a72KBAvz9m-dS48dO_FvPRrXek3OK_PE5wFve8zIlMYpzQc7-Q_gxlr2GeMS64XVAlm0ykVv5IRdGUrrGSnoA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] BQA_J0rDyeJjTXmeODQ9yTfY8X16qnsl6vMouYs9TefqIif5MVlLIufIWRFrnvwi7bKK3ZSktAgy_a72KBAvz9m-dS48dO_FvPRrXek3OK_PE5wFve8zIlMYpzQc7-Q_gxlr2GeMS64XVAlm0ykVv5IRdGUrrGSnoA
Aug 30 07:13:59 volumio sudo[24874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 26120
Aug 30 07:13:59 volumio sudo[24874]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 30 07:13:59 volumio sudo[24874]: pam_unix(sudo:session): session closed for user root
Aug 30 07:13:59 volumio shairport-sync[26120]: {"time":1598740375949,"response":"airplayInactive Success"}
Aug 30 07:13:59 volumio systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2
Aug 30 07:13:59 volumio systemd[1]: Unit shairport-sync.service entered failed state.
Aug 30 07:13:59 volumio volumio[901]: info: Shairport-Sync paused with USR2
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Vollibrespot Active
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Currently active: airplay_emulation
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Setting Volatile state to Volspotconnect2
Aug 30 07:13:59 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 30 07:13:59 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 30 07:13:59 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:13:59 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Setting VLS device_id: c6e56cf6b68c07041768b06e2f29a439e7724b0e
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:2ISApOKCrS01UHdjUWvEbT">
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : (222096 ms) loaded
Aug 30 07:13:59 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track } }
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Device palyback is inactive
Aug 30 07:13:59 volumio volumio[901]: [SpotifyConnect] Device is paused
Aug 30 07:14:00 volumio volumio[901]: info: Restarting Shairport-Sync after stop
Aug 30 07:14:00 volumio sudo[24885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 30 07:14:00 volumio sudo[24885]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 30 07:14:00 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver...
Aug 30 07:14:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Aug 30 07:14:00 volumio sudo[24885]: pam_unix(sudo:session): session closed for user root
Aug 30 07:14:00 volumio volumio[901]: info: Shairport-Sync retarted
Aug 30 07:14:28 volumio volumio[1123]: [Vollibrespot] : Fetching autoplay context uri
Aug 30 07:14:28 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 56272229512177216788502090367291423695, audio_type: Track }, track_id: SpotifyId { id: 94647820975171032235243086362484897466, audio_type: Track } }
Aug 30 07:14:28 volumio volumio[1123]: [Vollibrespot] : No autoplay_uri found
Aug 30 07:14:28 volumio volumio[1123]: [Vollibrespot] : AutoplayError: MercuryError
Aug 30 07:14:28 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:14:28 volumio volumio[1123]: [Vollibrespot] : (219834 ms) loaded
Aug 30 07:14:28 volumio volumio[1123]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 94647820975171032235243086362484897466, audio_type: Track } }
Aug 30 07:14:28 volumio volumio[1123]: [Vollibrespot] : Event: SinkActive
Aug 30 07:14:28 volumio volumio[901]: [SpotifyConnect] Rely On Me
Aug 30 07:14:28 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:14:28 volumio volumio[901]: [SpotifyConnect] Sink acquired
Aug 30 07:14:28 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Aug 30 07:14:28 volumio volumio[901]: [SpotifyConnect] Currently active: volspotconnect2
Aug 30 07:14:28 volumio volumio[901]: [SpotifyConnect] Pushing new state :: true
Aug 30 07:14:28 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Aug 30 07:14:28 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:14:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:14:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:14:44 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 20469 }
Aug 30 07:14:44 volumio volumio[901]: [SpotifyConnect] 31.233691920347905
Aug 30 07:14:44 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:31.233691920347905 Volumio: 31
Aug 30 07:14:44 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:14:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:14:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:14:45 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21493 }
Aug 30 07:14:45 volumio volumio[901]: [SpotifyConnect] 32.79621576256962
Aug 30 07:14:45 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:32.79621576256962 Volumio: 33
Aug 30 07:14:45 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:14:45 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:14:45 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:14:46 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 22516 }
Aug 30 07:14:46 volumio volumio[901]: [SpotifyConnect] 34.35721370260166
Aug 30 07:14:46 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:34.35721370260166 Volumio: 34
Aug 30 07:14:46 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:14:46 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:14:46 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:14:46 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 23540 }
Aug 30 07:14:46 volumio volumio[901]: [SpotifyConnect] 35.91973754482338
Aug 30 07:14:46 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:35.91973754482338 Volumio: 36
Aug 30 07:14:46 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:14:46 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:14:46 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:14:47 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 24564 }
Aug 30 07:14:47 volumio volumio[901]: [SpotifyConnect] 37.48226138704509
Aug 30 07:14:47 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:37.48226138704509 Volumio: 37
Aug 30 07:14:47 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:14:47 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:14:47 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:14:49 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 25588 }
Aug 30 07:14:49 volumio volumio[901]: [SpotifyConnect] 39.04478522926681
Aug 30 07:14:49 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:39.04478522926681 Volumio: 39
Aug 30 07:14:49 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:14:49 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:14:49 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:14:53 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 26612 }
Aug 30 07:14:53 volumio volumio[901]: [SpotifyConnect] 40.607309071488515
Aug 30 07:14:53 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:40.607309071488515 Volumio: 41
Aug 30 07:14:53 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:14:53 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:14:53 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:17:37 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:17:37 volumio volumio[1123]: [Vollibrespot] : (219300 ms) loaded
Aug 30 07:18:07 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 242354551709661600453060920556794912716, audio_type: Track } }
Aug 30 07:18:07 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 94647820975171032235243086362484897466, audio_type: Track }, track_id: SpotifyId { id: 242354551709661600453060920556794912716, audio_type: Track } }
Aug 30 07:18:07 volumio volumio[901]: [SpotifyConnect] golden thing
Aug 30 07:18:07 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:21:17 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:21:17 volumio volumio[1123]: [Vollibrespot] : (267077 ms) loaded
Aug 30 07:21:46 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 243061660087042100117499792775920055710, audio_type: Track } }
Aug 30 07:21:46 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 242354551709661600453060920556794912716, audio_type: Track }, track_id: SpotifyId { id: 243061660087042100117499792775920055710, audio_type: Track } }
Aug 30 07:21:47 volumio volumio[901]: [SpotifyConnect] Feels Like Home
Aug 30 07:21:47 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:25:44 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:25:44 volumio volumio[1123]: [Vollibrespot] : (194523 ms) loaded
Aug 30 07:26:14 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 259051129950412631016636701959428544362, audio_type: Track } }
Aug 30 07:26:14 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 243061660087042100117499792775920055710, audio_type: Track }, track_id: SpotifyId { id: 259051129950412631016636701959428544362, audio_type: Track } }
Aug 30 07:26:14 volumio volumio[901]: [SpotifyConnect] How Good It Is
Aug 30 07:26:14 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:28:58 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:28:58 volumio volumio[1123]: [Vollibrespot] : (159437 ms) loaded
Aug 30 07:29:28 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 84519952716907235734927531609556477630, audio_type: Track } }
Aug 30 07:29:28 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 259051129950412631016636701959428544362, audio_type: Track }, track_id: SpotifyId { id: 84519952716907235734927531609556477630, audio_type: Track } }
Aug 30 07:29:28 volumio volumio[901]: [SpotifyConnect] Catching Feelings - Stripped
Aug 30 07:29:28 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:31:38 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:31:38 volumio volumio[1123]: [Vollibrespot] : (213855 ms) loaded
Aug 30 07:32:08 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 205764243976699910451100201318693149627, audio_type: Track } }
Aug 30 07:32:08 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 84519952716907235734927531609556477630, audio_type: Track }, track_id: SpotifyId { id: 205764243976699910451100201318693149627, audio_type: Track } }
Aug 30 07:32:08 volumio volumio[901]: [SpotifyConnect] Little Love
Aug 30 07:32:08 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:35:12 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:35:12 volumio volumio[1123]: [Vollibrespot] : (187400 ms) loaded
Aug 30 07:35:41 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 307474772712229929783827306330233344091, audio_type: Track } }
Aug 30 07:35:41 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 205764243976699910451100201318693149627, audio_type: Track }, track_id: SpotifyId { id: 307474772712229929783827306330233344091, audio_type: Track } }
Aug 30 07:35:42 volumio volumio[901]: [SpotifyConnect] Colors
Aug 30 07:35:42 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:38:19 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:38:19 volumio volumio[1123]: [Vollibrespot] : (171786 ms) loaded
Aug 30 07:38:49 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 139792074932369672356738018366461824777, audio_type: Track } }
Aug 30 07:38:49 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 307474772712229929783827306330233344091, audio_type: Track }, track_id: SpotifyId { id: 139792074932369672356738018366461824777, audio_type: Track } }
Aug 30 07:38:49 volumio volumio[901]: [SpotifyConnect] Behind the Light
Aug 30 07:38:49 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:41:11 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:41:11 volumio volumio[1123]: [Vollibrespot] : (171908 ms) loaded
Aug 30 07:41:41 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 305412943081170127928554401975871099009, audio_type: Track } }
Aug 30 07:41:41 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 139792074932369672356738018366461824777, audio_type: Track }, track_id: SpotifyId { id: 305412943081170127928554401975871099009, audio_type: Track } }
Aug 30 07:41:41 volumio volumio[901]: [SpotifyConnect] Dreamers Of The Shore
Aug 30 07:41:41 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:44:03 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:44:03 volumio volumio[1123]: [Vollibrespot] : (195480 ms) loaded
Aug 30 07:44:32 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 92474539869642500135958674605530803806, audio_type: Track } }
Aug 30 07:44:32 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 305412943081170127928554401975871099009, audio_type: Track }, track_id: SpotifyId { id: 92474539869642500135958674605530803806, audio_type: Track } }
Aug 30 07:44:33 volumio volumio[901]: [SpotifyConnect] T-Shirts - Acoustic
Aug 30 07:44:33 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:47:18 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:47:18 volumio volumio[1123]: [Vollibrespot] : (189546 ms) loaded
Aug 30 07:47:48 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 327186770470072343243115350070181513032, audio_type: Track } }
Aug 30 07:47:48 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 92474539869642500135958674605530803806, audio_type: Track }, track_id: SpotifyId { id: 327186770470072343243115350070181513032, audio_type: Track } }
Aug 30 07:47:48 volumio volumio[901]: [SpotifyConnect] The Keeper - Acoustic
Aug 30 07:47:48 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:50:28 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:50:28 volumio volumio[1123]: [Vollibrespot] : (172912 ms) loaded
Aug 30 07:50:58 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 133575113891899993026319611407158536464, audio_type: Track } }
Aug 30 07:50:58 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 327186770470072343243115350070181513032, audio_type: Track }, track_id: SpotifyId { id: 133575113891899993026319611407158536464, audio_type: Track } }
Aug 30 07:50:58 volumio volumio[901]: [SpotifyConnect] All Time Low (Acoustic)
Aug 30 07:50:58 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:53:20 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:53:21 volumio volumio[1123]: [Vollibrespot] : (201694 ms) loaded
Aug 30 07:53:50 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 50739898359086185725633608442101458530, audio_type: Track } }
Aug 30 07:53:50 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 133575113891899993026319611407158536464, audio_type: Track }, track_id: SpotifyId { id: 50739898359086185725633608442101458530, audio_type: Track } }
Aug 30 07:53:51 volumio volumio[901]: [SpotifyConnect] Favourite Ex
Aug 30 07:53:51 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:56:42 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:56:42 volumio volumio[1123]: [Vollibrespot] : (176880 ms) loaded
Aug 30 07:57:12 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 99797104541868420417955813608302122937, audio_type: Track } }
Aug 30 07:57:12 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 50739898359086185725633608442101458530, audio_type: Track }, track_id: SpotifyId { id: 99797104541868420417955813608302122937, audio_type: Track } }
Aug 30 07:57:12 volumio volumio[901]: [SpotifyConnect] Peer Pressure (Acoustic) [feat. Julia Michaels]
Aug 30 07:57:12 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 07:59:02 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 25588 }
Aug 30 07:59:02 volumio volumio[901]: [SpotifyConnect] 39.04478522926681
Aug 30 07:59:02 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:39.04478522926681 Volumio: 39
Aug 30 07:59:02 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:59:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:59:02 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:59:02 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 24564 }
Aug 30 07:59:02 volumio volumio[901]: [SpotifyConnect] 37.48226138704509
Aug 30 07:59:02 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:37.48226138704509 Volumio: 37
Aug 30 07:59:02 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:59:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:59:02 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:59:04 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 23540 }
Aug 30 07:59:04 volumio volumio[901]: [SpotifyConnect] 35.91973754482338
Aug 30 07:59:04 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:35.91973754482338 Volumio: 36
Aug 30 07:59:04 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:59:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:59:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:59:06 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 22516 }
Aug 30 07:59:06 volumio volumio[901]: [SpotifyConnect] 34.35721370260166
Aug 30 07:59:06 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:34.35721370260166 Volumio: 34
Aug 30 07:59:06 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:59:06 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:59:06 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:59:06 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21492 }
Aug 30 07:59:06 volumio volumio[901]: [SpotifyConnect] 32.79468986037995
Aug 30 07:59:06 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:32.79468986037995 Volumio: 33
Aug 30 07:59:06 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:59:06 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:59:06 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:59:08 volumio volumio[1123]: [Vollibrespot] : Event: Volume { volume_to_mixer: 20468 }
Aug 30 07:59:08 volumio volumio[901]: [SpotifyConnect] 31.232166018158235
Aug 30 07:59:08 volumio volumio[901]: [SpotifyConnect] Volume: Spotify:31.232166018158235 Volumio: 31
Aug 30 07:59:08 volumio volumio[901]: info: CoreStateMachine::pushState
Aug 30 07:59:08 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Aug 30 07:59:08 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 07:59:39 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 07:59:39 volumio volumio[1123]: [Vollibrespot] : (123708 ms) loaded
Aug 30 08:00:09 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 284972631898034061912341830891707696835, audio_type: Track } }
Aug 30 08:00:09 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 99797104541868420417955813608302122937, audio_type: Track }, track_id: SpotifyId { id: 284972631898034061912341830891707696835, audio_type: Track } }
Aug 30 08:00:09 volumio volumio[901]: [SpotifyConnect] Beyond Words
Aug 30 08:00:09 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:01:43 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:01:43 volumio volumio[1123]: [Vollibrespot] : (216115 ms) loaded
Aug 30 08:02:13 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 155744852666932522107665970480707835307, audio_type: Track } }
Aug 30 08:02:13 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 284972631898034061912341830891707696835, audio_type: Track }, track_id: SpotifyId { id: 155744852666932522107665970480707835307, audio_type: Track } }
Aug 30 08:02:13 volumio volumio[901]: [SpotifyConnect] High Hopes
Aug 30 08:02:13 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:05:19 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:05:19 volumio volumio[1123]: [Vollibrespot] : (168087 ms) loaded
Aug 30 08:05:49 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 49518057822592443356189769242913044893, audio_type: Track } }
Aug 30 08:05:49 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 155744852666932522107665970480707835307, audio_type: Track }, track_id: SpotifyId { id: 49518057822592443356189769242913044893, audio_type: Track } }
Aug 30 08:05:49 volumio volumio[901]: [SpotifyConnect] Just Another Love Song
Aug 30 08:05:49 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:08:07 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:08:07 volumio volumio[1123]: [Vollibrespot] : (216776 ms) loaded
Aug 30 08:08:37 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 61938889393541954178722247165449694504, audio_type: Track } }
Aug 30 08:08:37 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 49518057822592443356189769242913044893, audio_type: Track }, track_id: SpotifyId { id: 61938889393541954178722247165449694504, audio_type: Track } }
Aug 30 08:08:37 volumio volumio[901]: [SpotifyConnect] Sing to You - Acoustic
Aug 30 08:08:37 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:11:44 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:11:44 volumio volumio[1123]: [Vollibrespot] : (167499 ms) loaded
Aug 30 08:11:59 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 08:11:59 volumio volumio[901]: [SpotifyConnect] BQBnj1QLi3gQZvdFzIN_JvmnzCX_2dLuDw3_0zD0q3REGmH8-9IZcimnDQEKACAhymT-B0hnA2ZVdnTJE7GHsdB4JNlcEsTIxe6CzOv7MsnbUpk33pn4ScjNufSk18E35DY94M6XOJX1z1i4wqAKQCCguKuBmcfm3g
Aug 30 08:11:59 volumio volumio[901]: [SpotifyConnect] Setting VLS device_id: c6e56cf6b68c07041768b06e2f29a439e7724b0e
Aug 30 08:12:14 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 297621877136503973619888919253862412078, audio_type: Track } }
Aug 30 08:12:14 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 61938889393541954178722247165449694504, audio_type: Track }, track_id: SpotifyId { id: 297621877136503973619888919253862412078, audio_type: Track } }
Aug 30 08:12:14 volumio volumio[901]: [SpotifyConnect] What A Time
Aug 30 08:12:14 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:14:31 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:14:31 volumio volumio[1123]: [Vollibrespot] : (183017 ms) loaded
Aug 30 08:15:01 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 192246157581124322617508105715077040139, audio_type: Track } }
Aug 30 08:15:01 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 297621877136503973619888919253862412078, audio_type: Track }, track_id: SpotifyId { id: 192246157581124322617508105715077040139, audio_type: Track } }
Aug 30 08:15:01 volumio volumio[901]: [SpotifyConnect] When We Were Young
Aug 30 08:15:01 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:17:34 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:17:34 volumio volumio[1123]: [Vollibrespot] : (203571 ms) loaded
Aug 30 08:18:04 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 128792048269570327511783787332266966012, audio_type: Track } }
Aug 30 08:18:04 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 192246157581124322617508105715077040139, audio_type: Track }, track_id: SpotifyId { id: 128792048269570327511783787332266966012, audio_type: Track } }
Aug 30 08:18:04 volumio volumio[901]: [SpotifyConnect] I Don't Care - Acoustic
Aug 30 08:18:04 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:20:58 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:20:58 volumio volumio[1123]: [Vollibrespot] : (184900 ms) loaded
Aug 30 08:21:28 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 230467502248646033312166915371610240671, audio_type: Track } }
Aug 30 08:21:28 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 128792048269570327511783787332266966012, audio_type: Track }, track_id: SpotifyId { id: 230467502248646033312166915371610240671, audio_type: Track } }
Aug 30 08:21:28 volumio volumio[901]: [SpotifyConnect] Look Away - Acoustic
Aug 30 08:21:28 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:24:03 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:24:03 volumio volumio[1123]: [Vollibrespot] : (217586 ms) loaded
Aug 30 08:24:33 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 97940615302787562240620933059906238027, audio_type: Track } }
Aug 30 08:24:33 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 230467502248646033312166915371610240671, audio_type: Track }, track_id: SpotifyId { id: 97940615302787562240620933059906238027, audio_type: Track } }
Aug 30 08:24:33 volumio volumio[901]: [SpotifyConnect] Polaroid - Acoustic
Aug 30 08:24:33 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:27:40 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:27:40 volumio volumio[1123]: [Vollibrespot] : (238220 ms) loaded
Aug 30 08:28:10 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 59263299818716041105374307603551347600, audio_type: Track } }
Aug 30 08:28:10 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 97940615302787562240620933059906238027, audio_type: Track }, track_id: SpotifyId { id: 59263299818716041105374307603551347600, audio_type: Track } }
Aug 30 08:28:10 volumio volumio[901]: [SpotifyConnect] I Don't Care - Acoustic
Aug 30 08:28:10 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:31:39 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:31:39 volumio volumio[1123]: [Vollibrespot] : (165506 ms) loaded
Aug 30 08:32:09 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 247874664872501510002907140021451210187, audio_type: Track } }
Aug 30 08:32:09 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 59263299818716041105374307603551347600, audio_type: Track }, track_id: SpotifyId { id: 247874664872501510002907140021451210187, audio_type: Track } }
Aug 30 08:32:09 volumio volumio[901]: [SpotifyConnect] Million Ways - Acoustic
Aug 30 08:32:09 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:34:24 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:34:24 volumio volumio[1123]: [Vollibrespot] : (231813 ms) loaded
Aug 30 08:34:54 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 42071336803397706187589049168608029864, audio_type: Track } }
Aug 30 08:34:54 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 247874664872501510002907140021451210187, audio_type: Track }, track_id: SpotifyId { id: 42071336803397706187589049168608029864, audio_type: Track } }
Aug 30 08:34:54 volumio volumio[901]: [SpotifyConnect] Front Porch
Aug 30 08:34:54 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:38:16 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:38:16 volumio volumio[1123]: [Vollibrespot] : (227413 ms) loaded
Aug 30 08:38:46 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 234657371720558755778654289204162347719, audio_type: Track } }
Aug 30 08:38:46 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 42071336803397706187589049168608029864, audio_type: Track }, track_id: SpotifyId { id: 234657371720558755778654289204162347719, audio_type: Track } }
Aug 30 08:38:46 volumio volumio[901]: [SpotifyConnect] Sorry I'm Gone (feat. Emily Reid)
Aug 30 08:38:46 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:42:03 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:42:03 volumio volumio[1123]: [Vollibrespot] : (153066 ms) loaded
Aug 30 08:42:33 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 325187077476694839562189677849717834794, audio_type: Track } }
Aug 30 08:42:33 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 234657371720558755778654289204162347719, audio_type: Track }, track_id: SpotifyId { id: 325187077476694839562189677849717834794, audio_type: Track } }
Aug 30 08:42:33 volumio volumio[901]: [SpotifyConnect] I'm In A Hurry
Aug 30 08:42:33 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:44:36 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:44:36 volumio volumio[1123]: [Vollibrespot] : (228506 ms) loaded
Aug 30 08:45:06 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 105548297152953771782849393499049164316, audio_type: Track } }
Aug 30 08:45:06 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 325187077476694839562189677849717834794, audio_type: Track }, track_id: SpotifyId { id: 105548297152953771782849393499049164316, audio_type: Track } }
Aug 30 08:45:07 volumio volumio[901]: [SpotifyConnect] Love Me Now - Recorded at Spotify Studios NYC
Aug 30 08:45:07 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:48:25 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:48:25 volumio volumio[1123]: [Vollibrespot] : (187186 ms) loaded
Aug 30 08:48:55 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 201952029656251444843513899385523282118, audio_type: Track } }
Aug 30 08:48:55 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 105548297152953771782849393499049164316, audio_type: Track }, track_id: SpotifyId { id: 201952029656251444843513899385523282118, audio_type: Track } }
Aug 30 08:48:55 volumio volumio[901]: [SpotifyConnect] Telescope - Acoustic
Aug 30 08:48:55 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:51:32 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:51:32 volumio volumio[1123]: [Vollibrespot] : (201508 ms) loaded
Aug 30 08:52:02 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 110379977495346653311369763464633003141, audio_type: Track } }
Aug 30 08:52:02 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 201952029656251444843513899385523282118, audio_type: Track }, track_id: SpotifyId { id: 110379977495346653311369763464633003141, audio_type: Track } }
Aug 30 08:52:02 volumio volumio[901]: [SpotifyConnect] I Like Me Better
Aug 30 08:52:02 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:54:54 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:54:54 volumio volumio[1123]: [Vollibrespot] : (194884 ms) loaded
Aug 30 08:55:24 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 294853124469496582782666663664820275986, audio_type: Track } }
Aug 30 08:55:24 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 110379977495346653311369763464633003141, audio_type: Track }, track_id: SpotifyId { id: 294853124469496582782666663664820275986, audio_type: Track } }
Aug 30 08:55:24 volumio volumio[901]: [SpotifyConnect] South of the Border (feat. Camila Cabello) - Acoustic
Aug 30 08:55:24 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 08:58:08 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 08:58:09 volumio volumio[1123]: [Vollibrespot] : (181897 ms) loaded
Aug 30 08:58:38 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 21854863548749270678226627210867803268, audio_type: Track } }
Aug 30 08:58:38 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 294853124469496582782666663664820275986, audio_type: Track }, track_id: SpotifyId { id: 21854863548749270678226627210867803268, audio_type: Track } }
Aug 30 08:58:39 volumio volumio[901]: [SpotifyConnect] Sunflower
Aug 30 08:58:39 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:01:10 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:01:10 volumio volumio[1123]: [Vollibrespot] : (215628 ms) loaded
Aug 30 09:01:40 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 55318055993818265958505268702854307265, audio_type: Track } }
Aug 30 09:01:40 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 21854863548749270678226627210867803268, audio_type: Track }, track_id: SpotifyId { id: 55318055993818265958505268702854307265, audio_type: Track } }
Aug 30 09:01:40 volumio volumio[901]: [SpotifyConnect] Wake Up Sleeper - Acoustic
Aug 30 09:01:40 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:04:46 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:04:46 volumio volumio[1123]: [Vollibrespot] : (231762 ms) loaded
Aug 30 09:05:16 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 189610346354026405773507298595054070025, audio_type: Track } }
Aug 30 09:05:16 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 55318055993818265958505268702854307265, audio_type: Track }, track_id: SpotifyId { id: 189610346354026405773507298595054070025, audio_type: Track } }
Aug 30 09:05:16 volumio volumio[901]: [SpotifyConnect] A Thousand Ways (Acoustic)
Aug 30 09:05:16 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:08:38 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:08:38 volumio volumio[1123]: [Vollibrespot] : (185718 ms) loaded
Aug 30 09:09:08 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 262060202145355334370424365925675731043, audio_type: Track } }
Aug 30 09:09:08 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 189610346354026405773507298595054070025, audio_type: Track }, track_id: SpotifyId { id: 262060202145355334370424365925675731043, audio_type: Track } }
Aug 30 09:09:08 volumio volumio[901]: [SpotifyConnect] I Can't Help Myself (Sugar Pie, Honey Bunch)
Aug 30 09:09:08 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:09:59 volumio volumio[1123]: [Vollibrespot] : API Token expired, refreshing...
Aug 30 09:09:59 volumio volumio[901]: [SpotifyConnect] BQDPFMJRS6uqCZOOV5xCArShoz3Lo0ze0KuqRKCqGojbSn9YzwuhG2de0CjptRRrLaXAIkh1QcTz8JDBPhgnmDur7tQsHj8DF4wNZE_kfIVPF-rcZ_nHwHMBrw2E3tsNhV97i2GsZC6_07IUJtH8l-v8nXHKi6Gr7g
Aug 30 09:10:00 volumio volumio[901]: [SpotifyConnect] Setting VLS device_id: c6e56cf6b68c07041768b06e2f29a439e7724b0e
Aug 30 09:11:43 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:11:44 volumio volumio[1123]: [Vollibrespot] : (189650 ms) loaded
Aug 30 09:12:13 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 275930859003422645852757631440479113725, audio_type: Track } }
Aug 30 09:12:13 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 262060202145355334370424365925675731043, audio_type: Track }, track_id: SpotifyId { id: 275930859003422645852757631440479113725, audio_type: Track } }
Aug 30 09:12:14 volumio volumio[901]: [SpotifyConnect] Lovely
Aug 30 09:12:14 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:14:53 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:14:53 volumio volumio[1123]: [Vollibrespot] : (229960 ms) loaded
Aug 30 09:15:23 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 258117768695559089299756387503692463489, audio_type: Track } }
Aug 30 09:15:23 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 275930859003422645852757631440479113725, audio_type: Track }, track_id: SpotifyId { id: 258117768695559089299756387503692463489, audio_type: Track } }
Aug 30 09:15:23 volumio volumio[901]: [SpotifyConnect] No Right To Love You - Acoustic
Aug 30 09:15:23 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:18:43 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:18:43 volumio volumio[1123]: [Vollibrespot] : (210080 ms) loaded
Aug 30 09:19:13 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 175795885456979624206113871653824510402, audio_type: Track } }
Aug 30 09:19:13 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 258117768695559089299756387503692463489, audio_type: Track }, track_id: SpotifyId { id: 175795885456979624206113871653824510402, audio_type: Track } }
Aug 30 09:19:13 volumio volumio[901]: [SpotifyConnect] Bloom - Bonus Track
Aug 30 09:19:13 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:22:13 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:22:13 volumio volumio[1123]: [Vollibrespot] : (154663 ms) loaded
Aug 30 09:22:43 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273601513773902493054942906058164936679, audio_type: Track } }
Aug 30 09:22:43 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 175795885456979624206113871653824510402, audio_type: Track }, track_id: SpotifyId { id: 273601513773902493054942906058164936679, audio_type: Track } }
Aug 30 09:22:43 volumio volumio[901]: [SpotifyConnect] This Moment
Aug 30 09:22:43 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:24:48 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:24:48 volumio volumio[1123]: [Vollibrespot] : (184285 ms) loaded
Aug 30 09:25:18 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 4882589127743085948133924524877775150, audio_type: Track } }
Aug 30 09:25:18 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273601513773902493054942906058164936679, audio_type: Track }, track_id: SpotifyId { id: 4882589127743085948133924524877775150, audio_type: Track } }
Aug 30 09:25:18 volumio volumio[901]: [SpotifyConnect] Never Let You Go - Acoustic
Aug 30 09:25:18 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:27:52 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:27:52 volumio volumio[1123]: [Vollibrespot] : (162129 ms) loaded
Aug 30 09:28:22 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 91861287389047773563906432196451484891, audio_type: Track } }
Aug 30 09:28:22 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 4882589127743085948133924524877775150, audio_type: Track }, track_id: SpotifyId { id: 91861287389047773563906432196451484891, audio_type: Track } }
Aug 30 09:28:22 volumio volumio[901]: [SpotifyConnect] Stack It Up - Acoustic
Aug 30 09:28:22 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:30:34 volumio volumio[1123]: [Vollibrespot] : Loading with Spotify URI
Aug 30 09:30:34 volumio volumio[1123]: [Vollibrespot] : (211291 ms) loaded
Aug 30 09:31:04 volumio volumio[1123]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 120074374150743329974867271272072547712, audio_type: Track } }
Aug 30 09:31:04 volumio volumio[1123]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 91861287389047773563906432196451484891, audio_type: Track }, track_id: SpotifyId { id: 120074374150743329974867271272072547712, audio_type: Track } }
Aug 30 09:31:04 volumio volumio[901]: [SpotifyConnect] I Wanna Dance with Somebody - Acoustic
Aug 30 09:31:04 volumio volumio[901]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true }
Aug 30 09:34:06 volumio volumio[1123]: [Vollibrespot] : Loading