-- Logs begin at Fri 2024-03-29 14:08:41 UTC, end at Fri 2024-03-29 14:13:42 UTC. -- Mar 29 14:12:03 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:05 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:12:05 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:05 volumio sudo[2067]: root : TTY=unknown ; PWD=/home/volumio/PeppyMeter ; USER=root ; COMMAND=./run_peppymeter.sh Mar 29 14:12:05 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:12:06 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 11 Mar 29 14:12:06 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12 Mar 29 14:12:06 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:12:06 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:07 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:07 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root Mar 29 14:12:11 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:14 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 29 14:12:14 volumio volumio[848]: info: Share sda1 successfully unmounted Mar 29 14:12:14 volumio sudo[2116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.1/k3\sda1 /mnt/NAS/sda1 Mar 29 14:12:14 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:12:14 volumio kernel: CIFS: Attempting to mount //192.168.2.1/k3\sda1 Mar 29 14:12:14 volumio sudo[2116]: pam_unix(sudo:session): session closed for user root Mar 29 14:12:14 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -112 Mar 29 14:12:14 volumio volumio[848]: info: An error occurred mounting the new share. Rolling back configuration Mar 29 14:12:15 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:15 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 14:12:16 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 11 Mar 29 14:12:18 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:12:18 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:18 volumio sudo[2158]: root : TTY=unknown ; PWD=/home/volumio/PeppyMeter ; USER=root ; COMMAND=./run_peppymeter.sh Mar 29 14:12:18 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:12:19 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:19 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12 Mar 29 14:12:19 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 13 Mar 29 14:12:19 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:12:19 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:23 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:27 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:31 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:35 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:12:35 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 14 Mar 29 14:12:35 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:12:35 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:39 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:12:39 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:47 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 29 14:12:47 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:47 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 29 14:12:47 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:49 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 29 14:12:49 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D Mar 29 14:12:49 volumio volumio[848]: info: Preloading song: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D in service upnp_browser Mar 29 14:12:49 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 in service upnp_browser Mar 29 14:12:50 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::ClearQueue Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::stop Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::serviceStop Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::serviceStop Mar 29 14:12:51 volumio volumio[848]: info: ControllerMpd::stop Mar 29 14:12:51 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::clearPlayQueue Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::saveQueue Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::addQueueItems Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::addQueueItems Mar 29 14:12:51 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:51 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E Mar 29 14:12:51 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E in service upnp_browser Mar 29 14:12:51 volumio volumio[848]: info: sendMpdCommand stop took 145 milliseconds Mar 29 14:12:51 volumio volumio[848]: info: Mar 29 14:12:51 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:51 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:51 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:51 volumio volumio[848]: info: sendMpdCommand status took 1 milliseconds Mar 29 14:12:51 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:51 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:51 volumio volumio[848]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 14:12:51 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:51 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:51 volumio volumio[848]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"太傻","artist":"巫启贤","album":"爱情海","uri":"INTERNAL/01-巫启贤-太傻.mp3","trackType":"mp3"} Mar 29 14:12:51 volumio volumio[848]: verbose: CURRENT POSITION 0 Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:51 volumio volumio[848]: info: No code Mar 29 14:12:51 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:51 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:51 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:51 volumio volumio[848]: info: ------------------------------ 24ms Mar 29 14:12:52 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 15 Mar 29 14:12:52 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:12:52 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:55 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:55 volumio volumio[848]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::ClearQueue Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::stop Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:55 volumio volumio[848]: info: CorePlayQueue::clearPlayQueue Mar 29 14:12:55 volumio volumio[848]: info: CorePlayQueue::saveQueue Mar 29 14:12:55 volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::addQueueItems Mar 29 14:12:55 volumio volumio[848]: info: CorePlayQueue::addQueueItems Mar 29 14:12:55 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 Mar 29 14:12:55 volumio volumio[848]: info: Exploding uri upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 in service upnp_browser Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D Mar 29 14:12:55 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E Mar 29 14:12:55 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E Mar 29 14:12:55 volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:55 volumio volumio[848]: info: CorePlayQueue::saveQueue Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:55 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:55 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::play index 8 Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::stop Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::play index undefined Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:55 volumio volumio[848]: info: CorePlayQueue::getTrack 8 Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:55 volumio volumio[848]: info: CorePlayQueue::getTrack 8 Mar 29 14:12:55 volumio volumio[848]: info: [1711721575722] ControllerUPNPBrowser::clearAddPlayTrack Mar 29 14:12:55 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:55 volumio volumio[848]: info: sendMpdCommand stop took 1 milliseconds Mar 29 14:12:55 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:55 volumio volumio[848]: info: Mar 29 14:12:55 volumio volumio[848]: ---------------------------- MPD announces system playlist update Mar 29 14:12:55 volumio volumio[848]: info: Ignoring MPD Status Update Mar 29 14:12:55 volumio volumio[848]: info: sendMpdCommand clear took 2 milliseconds Mar 29 14:12:55 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.1:8200/MediaItems/52.wav" Mar 29 14:12:55 volumio volumio[848]: error: updateQueue error: null Mar 29 14:12:55 volumio volumio[848]: info: ------------------------------ 1ms Mar 29 14:12:55 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.1:8200/MediaItems/52.wav" Mar 29 14:12:55 volumio volumio[848]: info: Mar 29 14:12:55 volumio volumio[848]: ---------------------------- MPD announces system playlist update Mar 29 14:12:55 volumio volumio[848]: info: Ignoring MPD Status Update Mar 29 14:12:55 volumio volumio[848]: info: sendMpdCommand add "http://192.168.2.1:8200/MediaItems/52.wav" took 3 milliseconds Mar 29 14:12:55 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 14:12:55 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:12:55 volumio volumio[848]: info: ------------------------------ 2ms Mar 29 14:12:55 volumio volumio[848]: info: sendMpdCommand play took 1 milliseconds Mar 29 14:12:55 volumio volumio[848]: info: Mar 29 14:12:55 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:55 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:55 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:56 volumio volumio[848]: info: Mar 29 14:12:56 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:56 volumio volumio[848]: info: sendMpdCommand status took 161 milliseconds Mar 29 14:12:56 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:56 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:56 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:56 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:56 volumio volumio[848]: info: sendMpdCommand status took 1 milliseconds Mar 29 14:12:56 volumio volumio[848]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 14:12:56 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:56 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:56 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:56 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:56 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:56 volumio volumio[848]: info: CorePlayQueue::getTrack 8 Mar 29 14:12:56 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"52.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/52.wav","trackType":"wav"} Mar 29 14:12:56 volumio volumio[848]: verbose: CURRENT POSITION 8 Mar 29 14:12:56 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:12:56 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:12:56 volumio volumio[848]: info: ------------------------------ 164ms Mar 29 14:12:56 volumio volumio[848]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 14:12:56 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:56 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:56 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:56 volumio volumio[848]: info: CorePlayQueue::getTrack 8 Mar 29 14:12:56 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"52.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/52.wav","trackType":"wav"} Mar 29 14:12:56 volumio volumio[848]: verbose: CURRENT POSITION 8 Mar 29 14:12:56 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:12:56 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:12:56 volumio volumio[848]: info: Received an update from plugin. extracting info from payload Mar 29 14:12:56 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:56 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:56 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:56 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:56 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:56 volumio volumio[848]: info: ------------------------------ 23ms Mar 29 14:12:57 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::ClearQueue Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::stop Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::getTrack 8 Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::serviceStop Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::getTrack 8 Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::serviceStop Mar 29 14:12:57 volumio volumio[848]: info: [1711721577148] ControllerUPNPBrowser::stop Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::clearPlayQueue Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::saveQueue Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::addQueueItems Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::addQueueItems Mar 29 14:12:57 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D Mar 29 14:12:57 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E Mar 29 14:12:57 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::saveQueue Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::play index 10 Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::stop Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::play index undefined Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::getTrack 10 Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::getTrack 10 Mar 29 14:12:57 volumio volumio[848]: info: [1711721577199] ControllerUPNPBrowser::clearAddPlayTrack Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:57 volumio volumio[848]: info: Mar 29 14:12:57 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand stop took 88 milliseconds Mar 29 14:12:57 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand stop took 37 milliseconds Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:57 volumio volumio[848]: info: Mar 29 14:12:57 volumio volumio[848]: ---------------------------- MPD announces system playlist update Mar 29 14:12:57 volumio volumio[848]: info: Ignoring MPD Status Update Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand status took 1 milliseconds Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand clear took 1 milliseconds Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.1:8200/MediaItems/56.wav" Mar 29 14:12:57 volumio volumio[848]: error: updateQueue error: null Mar 29 14:12:57 volumio volumio[848]: info: ------------------------------ 54ms Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand playlistinfo took 54 milliseconds Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.1:8200/MediaItems/56.wav" Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:57 volumio volumio[848]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 14:12:57 volumio volumio[848]: info: ------------------------------ 56ms Mar 29 14:12:57 volumio volumio[848]: info: Mar 29 14:12:57 volumio volumio[848]: ---------------------------- MPD announces system playlist update Mar 29 14:12:57 volumio volumio[848]: info: Ignoring MPD Status Update Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand add "http://192.168.2.1:8200/MediaItems/56.wav" took 1 milliseconds Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:12:57 volumio volumio[848]: info: ------------------------------ 1ms Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand play took 1 milliseconds Mar 29 14:12:57 volumio volumio[848]: info: Mar 29 14:12:57 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:57 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:57 volumio volumio[848]: info: Mar 29 14:12:57 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand status took 120 milliseconds Mar 29 14:12:57 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand status took 1 milliseconds Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:57 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::getTrack 10 Mar 29 14:12:57 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"56.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/56.wav","trackType":"wav"} Mar 29 14:12:57 volumio volumio[848]: verbose: CURRENT POSITION 10 Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:12:57 volumio volumio[848]: info: ------------------------------ 123ms Mar 29 14:12:57 volumio volumio[848]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 14:12:57 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:57 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:57 volumio volumio[848]: info: CorePlayQueue::getTrack 10 Mar 29 14:12:57 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"56.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/56.wav","trackType":"wav"} Mar 29 14:12:57 volumio volumio[848]: verbose: CURRENT POSITION 10 Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:12:57 volumio volumio[848]: info: Received an update from plugin. extracting info from payload Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:57 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:57 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:57 volumio volumio[848]: info: ------------------------------ 26ms Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::saveQueue Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::play index 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::stop Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 10 Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::serviceStop Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 10 Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::serviceStop Mar 29 14:12:58 volumio volumio[848]: info: [1711721578398] ControllerUPNPBrowser::stop Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:58 volumio volumio[848]: info: Mar 29 14:12:58 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand stop took 6 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::play index undefined Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: info: [1711721578410] ControllerUPNPBrowser::clearAddPlayTrack Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand status took 9 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand stop took 3 milliseconds Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:58 volumio volumio[848]: info: Mar 29 14:12:58 volumio volumio[848]: ---------------------------- MPD announces system playlist update Mar 29 14:12:58 volumio volumio[848]: info: Ignoring MPD Status Update Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand clear took 2 milliseconds Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.1:8200/MediaItems/42.wav" Mar 29 14:12:58 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"56.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/56.wav","trackType":"wav"} Mar 29 14:12:58 volumio volumio[848]: verbose: CURRENT POSITION 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:58 volumio volumio[848]: info: No code Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:58 volumio volumio[848]: info: ------------------------------ 47ms Mar 29 14:12:58 volumio volumio[848]: error: updateQueue error: null Mar 29 14:12:58 volumio volumio[848]: info: ------------------------------ 35ms Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.1:8200/MediaItems/42.wav" Mar 29 14:12:58 volumio volumio[848]: info: Mar 29 14:12:58 volumio volumio[848]: ---------------------------- MPD announces system playlist update Mar 29 14:12:58 volumio volumio[848]: info: Ignoring MPD Status Update Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand add "http://192.168.2.1:8200/MediaItems/42.wav" took 1 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:12:58 volumio volumio[848]: info: ------------------------------ 2ms Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand play took 1 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: Mar 29 14:12:58 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:58 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:58 volumio volumio[848]: info: Mar 29 14:12:58 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand status took 117 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand status took 1 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:58 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/42.wav","trackType":"wav"} Mar 29 14:12:58 volumio volumio[848]: verbose: CURRENT POSITION 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:12:58 volumio volumio[848]: info: ------------------------------ 121ms Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:58 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/42.wav","trackType":"wav"} Mar 29 14:12:58 volumio volumio[848]: verbose: CURRENT POSITION 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:12:58 volumio volumio[848]: info: Received an update from plugin. extracting info from payload Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:58 volumio volumio[848]: info: ------------------------------ 32ms Mar 29 14:12:58 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::ClearQueue Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::stop Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::serviceStop Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 3 Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::serviceStop Mar 29 14:12:58 volumio volumio[848]: info: [1711721578892] ControllerUPNPBrowser::stop Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::clearPlayQueue Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::saveQueue Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::addQueueItems Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::addQueueItems Mar 29 14:12:58 volumio volumio[848]: info: Preload queue cleared Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$0 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$6 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$7 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$8 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$9 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$A Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$B Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$C Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$D Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$E Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$F Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$10 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$11 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$12 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$13 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$14 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$16 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$17 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$18 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$19 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1A Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1B Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1C Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1D Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1E Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$1F Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$20 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$21 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$22 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$23 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$24 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$25 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$26 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$27 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$28 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$29 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2A Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2B Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2C Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2D Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2E Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$2F Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$30 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$31 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$32 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$33 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$34 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$35 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$36 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$37 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$38 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$39 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3A Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3B Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3C Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3D Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3E Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$3F Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$40 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$41 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$42 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$43 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$44 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$45 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$46 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$47 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$48 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$49 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4A Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4B Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4C Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4D Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4E Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$4F Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$50 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$51 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$52 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$53 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$54 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$55 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$56 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$57 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$58 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$59 Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5A Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5B Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5C Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5D Mar 29 14:12:58 volumio volumio[848]: info: Adding Item to queue: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E Mar 29 14:12:58 volumio volumio[848]: info: Using cached record of: upnp/http://192.168.2.1:8200/ctl/ContentDir@64$0$1$5E Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::saveQueue Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:58 volumio volumio[848]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::play index 11 Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::stop Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::play index undefined Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 11 Mar 29 14:12:58 volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:58 volumio volumio[848]: info: CorePlayQueue::getTrack 11 Mar 29 14:12:58 volumio volumio[848]: info: [1711721578942] ControllerUPNPBrowser::clearAddPlayTrack Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:58 volumio volumio[848]: info: Mar 29 14:12:58 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand stop took 75 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand stop took 28 milliseconds Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:58 volumio volumio[848]: info: Mar 29 14:12:58 volumio volumio[848]: ---------------------------- MPD announces system playlist update Mar 29 14:12:58 volumio volumio[848]: info: Ignoring MPD Status Update Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand status took 20 milliseconds Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand clear took 20 milliseconds Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.1:8200/MediaItems/57.wav" Mar 29 14:12:58 volumio volumio[848]: error: updateQueue error: null Mar 29 14:12:58 volumio volumio[848]: info: ------------------------------ 7ms Mar 29 14:12:58 volumio volumio[848]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 14:12:58 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:58 volumio volumio[848]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 14:12:58 volumio volumio[848]: info: ------------------------------ 33ms Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.1:8200/MediaItems/57.wav" Mar 29 14:12:59 volumio volumio[848]: info: Mar 29 14:12:59 volumio volumio[848]: ---------------------------- MPD announces system playlist update Mar 29 14:12:59 volumio volumio[848]: info: Ignoring MPD Status Update Mar 29 14:12:59 volumio volumio[848]: info: sendMpdCommand add "http://192.168.2.1:8200/MediaItems/57.wav" took 0 milliseconds Mar 29 14:12:59 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:12:59 volumio volumio[848]: info: ------------------------------ 1ms Mar 29 14:12:59 volumio volumio[848]: info: sendMpdCommand play took 0 milliseconds Mar 29 14:12:59 volumio volumio[848]: info: Mar 29 14:12:59 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:59 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:59 volumio volumio[848]: info: Mar 29 14:12:59 volumio volumio[848]: ---------------------------- MPD announces state update: player Mar 29 14:12:59 volumio volumio[848]: info: sendMpdCommand status took 119 milliseconds Mar 29 14:12:59 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:59 volumio volumio[848]: info: sendMpdCommand status took 0 milliseconds Mar 29 14:12:59 volumio volumio[848]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:59 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:59 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:59 volumio volumio[848]: info: CorePlayQueue::getTrack 11 Mar 29 14:12:59 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/57.wav","trackType":"wav"} Mar 29 14:12:59 volumio volumio[848]: verbose: CURRENT POSITION 11 Mar 29 14:12:59 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:12:59 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:12:59 volumio volumio[848]: info: ------------------------------ 121ms Mar 29 14:12:59 volumio volumio[848]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 14:12:59 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:59 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:12:59 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:12:59 volumio volumio[848]: info: CorePlayQueue::getTrack 11 Mar 29 14:12:59 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/57.wav","trackType":"wav"} Mar 29 14:12:59 volumio volumio[848]: verbose: CURRENT POSITION 11 Mar 29 14:12:59 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:12:59 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:12:59 volumio volumio[848]: info: Received an update from plugin. extracting info from payload Mar 29 14:12:59 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:59 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:59 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:59 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:12:59 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:59 volumio volumio[848]: info: ------------------------------ 23ms Mar 29 14:13:04 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:13:10 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 16 Mar 29 14:13:10 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:13:19 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:13:26 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 17 Mar 29 14:13:26 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 29 14:13:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:13:26 volumio volumio[848]: info: CURURI: music-library Mar 29 14:13:26 volumio volumio[848]: info: Preload queue cleared Mar 29 14:13:34 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 29 14:13:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand update Mar 29 14:13:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:13:34 volumio volumio[848]: info: Mar 29 14:13:34 volumio volumio[848]: ---------------------------- MPD announces state update: update Mar 29 14:13:34 volumio volumio[848]: info: sendMpdCommand update took 4 milliseconds Mar 29 14:13:34 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:13:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:13:34 volumio volumio[848]: info: sendMpdCommand status took 3 milliseconds Mar 29 14:13:34 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:13:34 volumio volumio[848]: info: Command Router : Notfying DB Updatetrue Mar 29 14:13:34 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:13:34 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:13:35 volumio volumio[848]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 14:13:35 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:13:35 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:13:35 volumio volumio[848]: info: CorePlayQueue::getTrack 11 Mar 29 14:13:35 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28002,"duration":225,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/57.wav","trackType":"wav"} Mar 29 14:13:35 volumio volumio[848]: verbose: CURRENT POSITION 11 Mar 29 14:13:35 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:13:35 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:13:35 volumio volumio[848]: info: Received an update from plugin. extracting info from payload Mar 29 14:13:35 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:13:35 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:13:35 volumio volumio[848]: info: ------------------------------ 70ms Mar 29 14:13:35 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:13:35 volumio volumio[848]: info: Mar 29 14:13:35 volumio volumio[848]: ---------------------------- MPD announces state update: update Mar 29 14:13:35 volumio volumio[848]: info: ControllerMpd::getState Mar 29 14:13:35 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:13:35 volumio volumio[848]: info: sendMpdCommand status took 1 milliseconds Mar 29 14:13:35 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:13:35 volumio volumio[848]: info: Command Router : Notfying DB Updatefalse Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:13:35 volumio volumio[848]: info: sendMpdCommand status took 30 milliseconds Mar 29 14:13:35 volumio volumio[848]: verbose: ControllerMpd::parseState Mar 29 14:13:35 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:13:35 volumio volumio[848]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 14:13:35 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:13:35 volumio volumio[848]: info: ControllerMpd::pushState Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::servicePushState Mar 29 14:13:35 volumio volumio[848]: info: CorePlayQueue::getTrack 11 Mar 29 14:13:35 volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28111,"duration":225,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57.wav","artist":null,"album":null,"uri":"http://192.168.2.1:8200/MediaItems/57.wav","trackType":"wav"} Mar 29 14:13:35 volumio volumio[848]: verbose: CURRENT POSITION 11 Mar 29 14:13:35 volumio volumio[848]: info: CoreStateMachine::syncState stateService play Mar 29 14:13:35 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:13:35 volumio volumio[848]: info: Received an update from plugin. extracting info from payload Mar 29 14:13:35 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:13:35 volumio volumio[848]: info: CoreStateMachine::pushState Mar 29 14:13:35 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Mar 29 14:13:35 volumio volumio[848]: info: ------------------------------ 71ms Mar 29 14:13:42 volumio volumio[848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 14:13:42 volumio volumio[848]: Error: connect ETIMEDOUT 67.228.235.93:80 Mar 29 14:13:42 volumio volumio[848]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 29 14:13:42 volumio volumio[848]: errno: -110, Mar 29 14:13:42 volumio volumio[848]: code: 'ETIMEDOUT', Mar 29 14:13:42 volumio volumio[848]: syscall: 'connect', Mar 29 14:13:42 volumio volumio[848]: address: '67.228.235.93', Mar 29 14:13:42 volumio volumio[848]: port: 80 Mar 29 14:13:42 volumio volumio[848]: } Mar 29 14:13:42 volumio volumio[848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 14:13:42 volumio sudo[2419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-29 14:12 Mar 29 14:13:42 volumio sudo[2419]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 06:04:32 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dd58c1c2d5a97e584de8089531ce548f"