-- Logs begin at Fri 2024-11-29 02:39:54 UTC, end at Fri 2024-11-29 03:01:40 UTC. -- Nov 29 03:00:04 volumio sudo[2797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 03:00:04 volumio sudo[2797]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 03:00:04 volumio sudo[2797]: pam_unix(sudo:session): session closed for user root Nov 29 03:00:04 volumio sudo[2800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 03:00:04 volumio sudo[2800]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 03:00:04 volumio sudo[2800]: pam_unix(sudo:session): session closed for user root Nov 29 03:00:04 volumio volumio[2507]: verbose: New Socket.io Connection to 192.168.68.92 from 192.168.68.242 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6 Nov 29 03:00:04 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:04 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:04 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:04 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:04 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:04 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:04 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:04 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:04 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:04 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:04 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:04 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 03:00:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 03:00:05 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:05 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: Listing playlists Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetQueue Nov 29 03:00:05 volumio volumio[2507]: info: CoreStateMachine::getQueue Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getQueue Nov 29 03:00:05 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:05 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:05 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:05 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:05 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:05 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 03:00:08 volumio volumio[2507]: info: CoreCommandRouter::volumioNext Nov 29 03:00:08 volumio volumio[2507]: info: CoreStateMachine::next Nov 29 03:00:08 volumio volumio[2507]: info: CoreStateMachine::stop Nov 29 03:00:08 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:08 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:08 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:08 volumio volumio[2507]: info: CorePlayQueue::getTrack 1 Nov 29 03:00:09 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:09 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:09 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:09 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:09 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:09 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:09 volumio volumio[2507]: info: [1732849209537] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:09 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:09 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:09 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:09 volumio volumio[2507]: info: Nov 29 03:00:09 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:09 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:09 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:09 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:09 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:09 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:09 volumio volumio[2507]: info: Nov 29 03:00:09 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:09 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:09 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 252 milliseconds Nov 29 03:00:09 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:09 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:09 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:09 volumio volumio[2507]: info: sendMpdCommand play took 1 milliseconds Nov 29 03:00:10 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:10 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:10 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:10 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:10 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:10 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:10 volumio volumio[2507]: info: [1732849210621] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:10 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:10 volumio volumio[2507]: info: Nov 29 03:00:10 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:10 volumio volumio[2507]: info: sendMpdCommand stop took 2 milliseconds Nov 29 03:00:10 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:10 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:10 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:10 volumio volumio[2507]: info: Nov 29 03:00:10 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:10 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:10 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:10 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:10 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:10 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:10 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:10 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:10 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:10 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 29 03:00:10 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:10 volumio volumio[2507]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 29 03:00:10 volumio volumio[2507]: info: ------------------------------ 6ms Nov 29 03:00:10 volumio volumio[2507]: info: Nov 29 03:00:10 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:10 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:10 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 253 milliseconds Nov 29 03:00:10 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:10 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:10 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:10 volumio volumio[2507]: info: sendMpdCommand play took 2 milliseconds Nov 29 03:00:11 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:11 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:11 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:11 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:11 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:11 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:11 volumio volumio[2507]: info: [1732849211315] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:11 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:11 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:11 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:11 volumio volumio[2507]: info: Nov 29 03:00:11 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:11 volumio volumio[2507]: info: Nov 29 03:00:11 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:11 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:11 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:11 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:11 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:11 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:11 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:11 volumio volumio[2507]: info: sendMpdCommand status took 2 milliseconds Nov 29 03:00:11 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:11 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:11 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:11 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:11 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:11 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:11 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:11 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:11 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:11 volumio volumio[2507]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 29 03:00:11 volumio volumio[2507]: info: ------------------------------ 14ms Nov 29 03:00:11 volumio volumio[2507]: info: Nov 29 03:00:11 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:11 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:11 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 254 milliseconds Nov 29 03:00:11 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:11 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:11 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:11 volumio volumio[2507]: info: sendMpdCommand play took 2 milliseconds Nov 29 03:00:12 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:12 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:12 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:12 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:12 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:12 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:12 volumio volumio[2507]: info: [1732849212936] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:12 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:12 volumio volumio[2507]: info: Nov 29 03:00:12 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:12 volumio volumio[2507]: info: sendMpdCommand stop took 2 milliseconds Nov 29 03:00:12 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:12 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:12 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:12 volumio volumio[2507]: info: Nov 29 03:00:12 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:12 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:12 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:12 volumio volumio[2507]: info: sendMpdCommand clear took 2 milliseconds Nov 29 03:00:12 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:12 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:12 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:12 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:12 volumio volumio[2507]: info: ------------------------------ 6ms Nov 29 03:00:12 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 29 03:00:12 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:12 volumio volumio[2507]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 29 03:00:12 volumio volumio[2507]: info: ------------------------------ 10ms Nov 29 03:00:13 volumio volumio[2507]: info: Nov 29 03:00:13 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:13 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:13 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 253 milliseconds Nov 29 03:00:13 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:13 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:13 volumio volumio[2507]: info: sendMpdCommand play took 2 milliseconds Nov 29 03:00:13 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:13 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:13 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:13 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:13 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:13 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:13 volumio volumio[2507]: info: [1732849213417] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:13 volumio volumio[2507]: info: Nov 29 03:00:13 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:13 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:13 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:13 volumio volumio[2507]: info: Nov 29 03:00:13 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:13 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:13 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:13 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:13 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:13 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:13 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:13 volumio volumio[2507]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 29 03:00:13 volumio volumio[2507]: info: ------------------------------ 6ms Nov 29 03:00:13 volumio volumio[2507]: info: Nov 29 03:00:13 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:13 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:13 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 253 milliseconds Nov 29 03:00:13 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:13 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:13 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:13 volumio volumio[2507]: info: sendMpdCommand play took 2 milliseconds Nov 29 03:00:15 volumio volumio[2507]: info: Nov 29 03:00:15 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:15 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:15 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:15 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:15 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:15 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:15 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 20/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:15 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus stop Nov 29 03:00:15 volumio volumio[2507]: info: ------------------------------ 5ms Nov 29 03:00:15 volumio volumio[2507]: info: Nov 29 03:00:15 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:15 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:15 volumio volumio[2507]: info: Nov 29 03:00:15 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:15 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:15 volumio volumio[2507]: info: Nov 29 03:00:15 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:15 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:15 volumio volumio[2507]: info: Nov 29 03:00:15 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:15 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:15 volumio volumio[2507]: info: ------------------------------ 6ms Nov 29 03:00:15 volumio volumio[2507]: info: sendMpdCommand status took 4 milliseconds Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:15 volumio volumio[2507]: info: ------------------------------ 5ms Nov 29 03:00:15 volumio volumio[2507]: info: sendMpdCommand status took 3 milliseconds Nov 29 03:00:15 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:15 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:15 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:15 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:15 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:15 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:15 volumio volumio[2507]: info: ------------------------------ 27ms Nov 29 03:00:15 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 29 03:00:15 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:15 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:15 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:15 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:15 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:15 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:15 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:15 volumio volumio[2507]: info: ------------------------------ 44ms Nov 29 03:00:17 volumio volumio[2507]: info: Nov 29 03:00:17 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:17 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:17 volumio volumio[2507]: info: Nov 29 03:00:17 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:17 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:17 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:17 volumio volumio[2507]: info: sendMpdCommand status took 3 milliseconds Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:17 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:17 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:17 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:17 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:17 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:17 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:17 volumio volumio[2507]: info: ------------------------------ 21ms Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::volumioNext Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::next Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::stop Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::stPlaybackTimer Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::updateTrackBlock Nov 29 03:00:17 volumio volumio[2507]: info: CorePlayQueue::getTrackBlock Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:17 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::serviceStop Nov 29 03:00:17 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::serviceStop Nov 29 03:00:17 volumio volumio[2507]: info: [1732849217545] ControllerWebradio::stop Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:17 volumio volumio[2507]: info: Nov 29 03:00:17 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:17 volumio volumio[2507]: info: sendMpdCommand stop took 94 milliseconds Nov 29 03:00:17 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:17 volumio volumio[2507]: info: CorePlayQueue::getTrack 1 Nov 29 03:00:17 volumio volumio[2507]: info: sendMpdCommand status took 31 milliseconds Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:17 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 29 03:00:17 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:17 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:17 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:17 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:17 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:17 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:17 volumio volumio[2507]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 29 03:00:17 volumio volumio[2507]: info: ------------------------------ 56ms Nov 29 03:00:18 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:18 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:18 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:18 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:18 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:18 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:18 volumio volumio[2507]: info: [1732849218421] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:18 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:18 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:18 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:18 volumio volumio[2507]: info: Nov 29 03:00:18 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:18 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:18 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:18 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:18 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:18 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:18 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 252 milliseconds Nov 29 03:00:18 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:18 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:18 volumio volumio[2507]: info: Nov 29 03:00:18 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:18 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:18 volumio volumio[2507]: info: sendMpdCommand play took 3 milliseconds Nov 29 03:00:18 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand status took 2 milliseconds Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 20/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:20 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus stop Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 6ms Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 20/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:20 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:20 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 34ms Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:20 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand status took 2 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"oldies-but-goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:20 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:20 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 28ms Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:20 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:20 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 8ms Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand status took 7 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 7ms Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand status took 9 milliseconds Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:20 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:20 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 52ms Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 35 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:20 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:20 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 63ms Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioStop Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::stop Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::stPlaybackTimer Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::updateTrackBlock Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrackBlock Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::serviceStop Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::serviceStop Nov 29 03:00:20 volumio volumio[2507]: info: [1732849220704] ControllerWebradio::stop Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:20 volumio volumio[2507]: info: Nov 29 03:00:20 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand stop took 86 milliseconds Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:20 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:20 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:20 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:20 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:20 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:20 volumio volumio[2507]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 29 03:00:20 volumio volumio[2507]: info: ------------------------------ 16ms Nov 29 03:00:21 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:21 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:21 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:21 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:21 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:21 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:21 volumio volumio[2507]: info: [1732849221525] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:21 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:21 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:21 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:21 volumio volumio[2507]: info: Nov 29 03:00:21 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:21 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:21 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:21 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:21 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:21 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:21 volumio volumio[2507]: info: Nov 29 03:00:21 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:21 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:21 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 253 milliseconds Nov 29 03:00:21 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:21 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:21 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:21 volumio volumio[2507]: info: sendMpdCommand play took 1 milliseconds Nov 29 03:00:22 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:22 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:22 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:22 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:22 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:22 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:22 volumio volumio[2507]: info: [1732849222449] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:22 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:22 volumio volumio[2507]: info: Nov 29 03:00:22 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:22 volumio volumio[2507]: info: sendMpdCommand stop took 2 milliseconds Nov 29 03:00:22 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:22 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:22 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:22 volumio volumio[2507]: info: Nov 29 03:00:22 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:22 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:22 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:22 volumio volumio[2507]: info: sendMpdCommand clear took 3 milliseconds Nov 29 03:00:22 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:22 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:22 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:22 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:22 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:22 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:22 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:22 volumio volumio[2507]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 29 03:00:22 volumio volumio[2507]: info: ------------------------------ 6ms Nov 29 03:00:22 volumio volumio[2507]: info: Nov 29 03:00:22 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:22 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:22 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 253 milliseconds Nov 29 03:00:22 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:22 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:22 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:22 volumio volumio[2507]: info: sendMpdCommand play took 1 milliseconds Nov 29 03:00:23 volumio volumio[2507]: info: CoreCommandRouter::volumioNext Nov 29 03:00:23 volumio volumio[2507]: info: CoreStateMachine::next Nov 29 03:00:23 volumio volumio[2507]: info: ControllerMpd::next Nov 29 03:00:23 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand next Nov 29 03:00:24 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:24 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:24 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:24 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:24 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:24 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:24 volumio volumio[2507]: info: [1732849224405] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:24 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:25 volumio volumio[2507]: info: Nov 29 03:00:25 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand next took 1579 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:25 volumio volumio[2507]: info: Nov 29 03:00:25 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand stop took 623 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:25 volumio volumio[2507]: info: Nov 29 03:00:25 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:25 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand status took 3 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:25 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:25 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:25 volumio volumio[2507]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Nov 29 03:00:25 volumio volumio[2507]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Nov 29 03:00:25 volumio volumio[2507]: info: ------------------------------ 8ms Nov 29 03:00:25 volumio volumio[2507]: info: ------------------------------ 6ms Nov 29 03:00:25 volumio volumio[2507]: info: Nov 29 03:00:25 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:25 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 253 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:25 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand play took 1 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:25 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:25 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:25 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:25 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:25 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:25 volumio volumio[2507]: info: [1732849225360] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:25 volumio volumio[2507]: info: Nov 29 03:00:25 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:25 volumio volumio[2507]: info: Nov 29 03:00:25 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:25 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand status took 2 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:25 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:25 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:25 volumio volumio[2507]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 29 03:00:25 volumio volumio[2507]: info: ------------------------------ 5ms Nov 29 03:00:25 volumio volumio[2507]: info: Nov 29 03:00:25 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:25 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 252 milliseconds Nov 29 03:00:25 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:25 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:25 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:25 volumio volumio[2507]: info: sendMpdCommand play took 2 milliseconds Nov 29 03:00:27 volumio volumio[2507]: info: Nov 29 03:00:27 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:27 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:27 volumio volumio[2507]: info: sendMpdCommand status took 0 milliseconds Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:27 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:27 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:27 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:27 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 20/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:27 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus stop Nov 29 03:00:27 volumio volumio[2507]: info: ------------------------------ 6ms Nov 29 03:00:27 volumio volumio[2507]: info: Nov 29 03:00:27 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:27 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:27 volumio volumio[2507]: info: Nov 29 03:00:27 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:27 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:27 volumio volumio[2507]: info: Nov 29 03:00:27 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:27 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:27 volumio volumio[2507]: info: Nov 29 03:00:27 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:27 volumio volumio[2507]: info: Nov 29 03:00:27 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:27 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:27 volumio volumio[2507]: info: Nov 29 03:00:27 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:27 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:27 volumio volumio[2507]: info: ------------------------------ 4ms Nov 29 03:00:27 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:27 volumio volumio[2507]: info: sendMpdCommand status took 3 milliseconds Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:27 volumio volumio[2507]: info: ------------------------------ 5ms Nov 29 03:00:27 volumio volumio[2507]: info: sendMpdCommand status took 4 milliseconds Nov 29 03:00:27 volumio volumio[2507]: info: ------------------------------ 4ms Nov 29 03:00:27 volumio volumio[2507]: info: sendMpdCommand status took 4 milliseconds Nov 29 03:00:27 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:27 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:27 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:27 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:27 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:27 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:27 volumio volumio[2507]: info: ------------------------------ 30ms Nov 29 03:00:27 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 29 03:00:27 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:27 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:27 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:27 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:27 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:27 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:27 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:27 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:27 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:27 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:27 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:27 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:27 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:27 volumio volumio[2507]: info: ------------------------------ 84ms Nov 29 03:00:27 volumio volumio[2507]: info: ------------------------------ 92ms Nov 29 03:00:28 volumio volumio[2507]: info: Nov 29 03:00:28 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:28 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:28 volumio volumio[2507]: info: Nov 29 03:00:28 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:28 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:28 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:28 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:28 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:28 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:28 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:28 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:28 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:28 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:28 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:28 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:28 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:28 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:28 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:28 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:28 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:28 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:28 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:28 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:28 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:28 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:28 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:28 volumio volumio[2507]: info: ------------------------------ 29ms Nov 29 03:00:30 volumio volumio[2507]: info: CoreCommandRouter::volumioNext Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::next Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::stop Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::stPlaybackTimer Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::updateTrackBlock Nov 29 03:00:30 volumio volumio[2507]: info: CorePlayQueue::getTrackBlock Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:30 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:30 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:30 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::serviceStop Nov 29 03:00:30 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:30 volumio volumio[2507]: info: CoreCommandRouter::serviceStop Nov 29 03:00:30 volumio volumio[2507]: info: [1732849230395] ControllerWebradio::stop Nov 29 03:00:30 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:30 volumio volumio[2507]: info: sendMpdCommand stop took 41 milliseconds Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:30 volumio volumio[2507]: info: CorePlayQueue::getTrack 1 Nov 29 03:00:30 volumio volumio[2507]: info: Nov 29 03:00:30 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:30 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:30 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:30 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:30 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:30 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:30 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 29 03:00:30 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:30 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:30 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:30 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:30 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:30 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:30 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:30 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:30 volumio volumio[2507]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 29 03:00:30 volumio volumio[2507]: info: ------------------------------ 20ms Nov 29 03:00:31 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:31 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:31 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:31 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:31 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:31 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:31 volumio volumio[2507]: info: [1732849231144] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:31 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:31 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:31 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:31 volumio volumio[2507]: info: Nov 29 03:00:31 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:31 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:31 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:31 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:31 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:31 volumio volumio[2507]: info: ------------------------------ 1ms Nov 29 03:00:31 volumio volumio[2507]: info: Nov 29 03:00:31 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:31 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:31 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 254 milliseconds Nov 29 03:00:31 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:31 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:31 volumio volumio[2507]: info: ------------------------------ 4ms Nov 29 03:00:31 volumio volumio[2507]: info: sendMpdCommand play took 1 milliseconds Nov 29 03:00:32 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:32 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:32 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:32 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:32 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:32 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:32 volumio volumio[2507]: info: [1732849232065] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:32 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:32 volumio volumio[2507]: info: Nov 29 03:00:32 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:32 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:32 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:32 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:32 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:32 volumio volumio[2507]: info: Nov 29 03:00:32 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:32 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:32 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:32 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:32 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:32 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:32 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:32 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:32 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:32 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:32 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:32 volumio volumio[2507]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 29 03:00:32 volumio volumio[2507]: info: ------------------------------ 5ms Nov 29 03:00:32 volumio volumio[2507]: info: Nov 29 03:00:32 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:32 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:32 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 252 milliseconds Nov 29 03:00:32 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:32 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:32 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:32 volumio volumio[2507]: info: sendMpdCommand play took 2 milliseconds Nov 29 03:00:33 volumio volumio[2507]: info: CoreCommandRouter::volumioPlay Nov 29 03:00:33 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:33 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:33 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:33 volumio volumio[2507]: info: CoreStateMachine::startPlaybackTimer Nov 29 03:00:33 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:33 volumio volumio[2507]: info: [1732849233635] ControllerWebradio::clearAddPlayTrack Nov 29 03:00:33 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:33 volumio volumio[2507]: info: sendMpdCommand stop took 1 milliseconds Nov 29 03:00:33 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 03:00:33 volumio volumio[2507]: info: Nov 29 03:00:33 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:33 volumio volumio[2507]: info: Nov 29 03:00:33 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:33 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:33 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:33 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:33 volumio volumio[2507]: info: sendMpdCommand clear took 1 milliseconds Nov 29 03:00:33 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 29 03:00:33 volumio volumio[2507]: error: updateQueue error: null Nov 29 03:00:33 volumio volumio[2507]: info: sendMpdCommand status took 2 milliseconds Nov 29 03:00:33 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:33 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:33 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:33 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:33 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:33 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:33 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:33 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:33 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:33 volumio volumio[2507]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 29 03:00:33 volumio volumio[2507]: info: ------------------------------ 13ms Nov 29 03:00:33 volumio volumio[2507]: info: Nov 29 03:00:33 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:33 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:33 volumio volumio[2507]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 252 milliseconds Nov 29 03:00:33 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 03:00:33 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand play Nov 29 03:00:33 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:33 volumio volumio[2507]: info: sendMpdCommand play took 2 milliseconds Nov 29 03:00:35 volumio volumio[2507]: info: Nov 29 03:00:35 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:35 volumio volumio[2507]: info: Nov 29 03:00:35 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand status took 3 milliseconds Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand status took 2 milliseconds Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:35 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:35 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 20/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:35 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus stop Nov 29 03:00:35 volumio volumio[2507]: info: ------------------------------ 10ms Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:35 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:35 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 20/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:35 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:35 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:35 volumio volumio[2507]: info: ------------------------------ 27ms Nov 29 03:00:35 volumio volumio[2507]: info: Nov 29 03:00:35 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:35 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:35 volumio volumio[2507]: info: Nov 29 03:00:35 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:35 volumio volumio[2507]: info: Nov 29 03:00:35 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:35 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:35 volumio volumio[2507]: info: Nov 29 03:00:35 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:35 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand status took 2 milliseconds Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:35 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand status took 3 milliseconds Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:35 volumio volumio[2507]: info: Nov 29 03:00:35 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:35 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:35 volumio volumio[2507]: info: Nov 29 03:00:35 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:35 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:35 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:35 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:35 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:35 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:35 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:35 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:35 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:35 volumio volumio[2507]: info: ------------------------------ 46ms Nov 29 03:00:35 volumio volumio[2507]: info: ------------------------------ 46ms Nov 29 03:00:35 volumio volumio[2507]: info: ------------------------------ 42ms Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand status took 41 milliseconds Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:35 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 29 03:00:35 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:35 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:35 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:35 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:35 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:35 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:35 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:35 volumio volumio[2507]: info: ------------------------------ 87ms Nov 29 03:00:37 volumio volumio[2507]: info: Nov 29 03:00:37 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:37 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:37 volumio volumio[2507]: info: Nov 29 03:00:37 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:37 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:37 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:37 volumio volumio[2507]: info: ------------------------------ 3ms Nov 29 03:00:37 volumio volumio[2507]: info: sendMpdCommand status took 2 milliseconds Nov 29 03:00:37 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:37 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:37 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:37 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:37 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:37 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:37 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2380,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:37 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:37 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:37 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:37 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:37 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:37 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:37 volumio volumio[2507]: info: ------------------------------ 19ms Nov 29 03:00:37 volumio volumio[2507]: info: Nov 29 03:00:37 volumio volumio[2507]: ---------------------------- MPD announces system playlist update Nov 29 03:00:37 volumio volumio[2507]: info: Ignoring MPD Status Update Nov 29 03:00:37 volumio volumio[2507]: info: Nov 29 03:00:37 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:37 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:37 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:37 volumio volumio[2507]: info: ------------------------------ 2ms Nov 29 03:00:37 volumio volumio[2507]: info: sendMpdCommand status took 1 milliseconds Nov 29 03:00:37 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:37 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:37 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 29 03:00:37 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:37 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:37 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:37 volumio volumio[2507]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3077,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 29 03:00:37 volumio volumio[2507]: verbose: CURRENT POSITION 0 Nov 29 03:00:37 volumio volumio[2507]: info: CoreStateMachine::syncState stateService play Nov 29 03:00:37 volumio volumio[2507]: info: CoreStateMachine::syncState currentStatus play Nov 29 03:00:37 volumio volumio[2507]: info: Received an update from plugin. extracting info from payload Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 29 03:00:37 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:37 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:37 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:37 volumio volumio[2507]: info: ------------------------------ 21ms Nov 29 03:00:51 volumio volumio[2507]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%2020%2F500000)%20ANTENNE%20BAYERN%20Oldies%20but%20Goldies%20(Germany)/0d7786d9-0872-41d4-ae30-82169c0a39fe.jpg' Nov 29 03:00:53 volumio volumio[2507]: verbose: New Socket.io Connection to 192.168.68.92 from 192.168.68.242 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Listing playlists Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetQueue Nov 29 03:00:53 volumio volumio[2507]: info: CoreStateMachine::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: CorePlayQueue::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Listing playlists Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetQueue Nov 29 03:00:53 volumio volumio[2507]: info: CoreStateMachine::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: CorePlayQueue::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Listing playlists Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetQueue Nov 29 03:00:53 volumio volumio[2507]: info: CoreStateMachine::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: CorePlayQueue::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Listing playlists Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetQueue Nov 29 03:00:53 volumio volumio[2507]: info: CoreStateMachine::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: CorePlayQueue::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Listing playlists Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetQueue Nov 29 03:00:53 volumio volumio[2507]: info: CoreStateMachine::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: CorePlayQueue::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Listing playlists Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetQueue Nov 29 03:00:53 volumio volumio[2507]: info: CoreStateMachine::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: CorePlayQueue::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Listing playlists Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetQueue Nov 29 03:00:53 volumio volumio[2507]: info: CoreStateMachine::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: CorePlayQueue::getQueue Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: Received Get System Info Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 03:00:53 volumio volumio[2507]: info: Discovery: Getting this device information Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::volumioGetState Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 03:00:53 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 03:00:54 volumio volumio[2507]: info: CoreCommandRouter::volumioNext Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::next Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::stop Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::stPlaybackTimer Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::updateTrackBlock Nov 29 03:00:54 volumio volumio[2507]: info: CorePlayQueue::getTrackBlock Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:54 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:54 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 03:00:54 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::serviceStop Nov 29 03:00:54 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:54 volumio volumio[2507]: info: CoreCommandRouter::serviceStop Nov 29 03:00:54 volumio volumio[2507]: info: [1732849254226] ControllerWebradio::stop Nov 29 03:00:54 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 03:00:54 volumio volumio[2507]: info: Nov 29 03:00:54 volumio volumio[2507]: ---------------------------- MPD announces state update: player Nov 29 03:00:54 volumio volumio[2507]: info: sendMpdCommand stop took 41 milliseconds Nov 29 03:00:54 volumio volumio[2507]: info: ControllerMpd::getState Nov 29 03:00:54 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand status Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::play index undefined Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 03:00:54 volumio volumio[2507]: info: CorePlayQueue::getTrack 1 Nov 29 03:00:54 volumio volumio[2507]: info: sendMpdCommand status took 3 milliseconds Nov 29 03:00:54 volumio volumio[2507]: verbose: ControllerMpd::parseState Nov 29 03:00:54 volumio volumio[2507]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 03:00:54 volumio volumio[2507]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 03:00:54 volumio volumio[2507]: verbose: ControllerMpd::parseTrackInfo Nov 29 03:00:54 volumio volumio[2507]: info: ControllerMpd::pushState Nov 29 03:00:54 volumio volumio[2507]: info: CoreCommandRouter::servicePushState Nov 29 03:00:54 volumio volumio[2507]: info: CoreStateMachine::pushState Nov 29 03:00:54 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:54 volumio volumio[2507]: info: CoreCommandRouter::volumioPushState Nov 29 03:00:54 volumio volumio[2507]: info: CorePlayQueue::getTrack 0 Nov 29 03:00:54 volumio volumio[2507]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 29 03:00:54 volumio volumio[2507]: info: ------------------------------ 18ms Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 29 03:01:04 volumio volumio[2507]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 29 03:01:39 volumio volumio[2507]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 03:01:39 volumio volumio[2507]: Error: connect ETIMEDOUT 108.160.166.148:80 Nov 29 03:01:39 volumio volumio[2507]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 29 03:01:39 volumio volumio[2507]: errno: -110, Nov 29 03:01:39 volumio volumio[2507]: code: 'ETIMEDOUT', Nov 29 03:01:39 volumio volumio[2507]: syscall: 'connect', Nov 29 03:01:39 volumio volumio[2507]: address: '108.160.166.148', Nov 29 03:01:39 volumio volumio[2507]: port: 80 Nov 29 03:01:39 volumio volumio[2507]: } Nov 29 03:01:39 volumio volumio[2507]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 03:01:40 volumio sudo[2828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 03:00 Nov 29 03:01:40 volumio sudo[2828]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 06:13:59 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6a6571e2ab129da64c127254a7a779b9"