-- Logs begin at Mon 2025-02-24 03:39:32 UTC, end at Mon 2025-02-24 05:00:52 UTC. -- Feb 24 04:59:01 volumio volumio[1075]: info: Preload queue cleared Feb 24 04:59:01 volumio volumio[1075]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::ClearQueue Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:01 volumio volumio[1075]: info: CorePlayQueue::clearPlayQueue Feb 24 04:59:01 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 04:59:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Feb 24 04:59:01 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Feb 24 04:59:01 volumio volumio[1075]: info: Preload queue cleared Feb 24 04:59:01 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1937063 Feb 24 04:59:01 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 04:59:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 04:59:01 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Feb 24 04:59:01 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Feb 24 04:59:01 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::play index 0 Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::play index undefined Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:01 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Feb 24 04:59:01 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:01 volumio volumio[1075]: info: [1740373141030] ControllerWebradio::clearAddPlayTrack Feb 24 04:59:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 04:59:01 volumio volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Feb 24 04:59:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 04:59:01 volumio volumio[1075]: info: Feb 24 04:59:01 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:01 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:01 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Feb 24 04:59:01 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1937063" Feb 24 04:59:01 volumio volumio[1075]: error: updateQueue error: null Feb 24 04:59:01 volumio volumio[1075]: info: ------------------------------ 2ms Feb 24 04:59:02 volumio volumio[1075]: info: Feb 24 04:59:02 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:02 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:02 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1937063" took 1150 milliseconds Feb 24 04:59:02 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Feb 24 04:59:02 volumio volumio[1075]: info: ------------------------------ 3ms Feb 24 04:59:02 volumio volumio[1075]: info: sendMpdCommand play took 2 milliseconds Feb 24 04:59:02 volumio volumio[1075]: info: Feb 24 04:59:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:02 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:02 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:02 volumio volumio[1075]: info: Feb 24 04:59:02 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:02 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:02 volumio volumio[1075]: info: Feb 24 04:59:02 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:02 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:02 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 04:59:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 04:59:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Music Freeze Your Mind -","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.135.44:5006","trackType":"44:5006"} Feb 24 04:59:02 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 04:59:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 04:59:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Feb 24 04:59:02 volumio volumio[1075]: info: ------------------------------ 8ms Feb 24 04:59:02 volumio volumio[1075]: info: ------------------------------ 5ms Feb 24 04:59:02 volumio volumio[1075]: info: sendMpdCommand status took 4 milliseconds Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:02 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 04:59:02 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:02 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 04:59:02 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 04:59:02 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:02 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"Music Freeze Your Mind -","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.135.44:5006","trackType":"44:5006"} Feb 24 04:59:02 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 04:59:02 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 04:59:02 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Feb 24 04:59:02 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Feb 24 04:59:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 04:59:02 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:02 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 04:59:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:02 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:02 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:02 volumio volumio[1075]: info: ------------------------------ 23ms Feb 24 04:59:03 volumio dhcpcd[1064]: eth0: probing for an IPv4LL address Feb 24 04:59:08 volumio dhcpcd[1064]: eth0: using IPv4LL address 169.254.13.24 Feb 24 04:59:08 volumio dhcpcd[1064]: eth0: adding route to 169.254.0.0/16 Feb 24 04:59:08 volumio avahi-daemon[764]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.13.24. Feb 24 04:59:08 volumio avahi-daemon[764]: New relevant interface eth0.IPv4 for mDNS. Feb 24 04:59:08 volumio avahi-daemon[764]: Registering new address record for 169.254.13.24 on eth0.IPv4. Feb 24 04:59:09 volumio dhcpcd[1064]: eth0: carrier lost Feb 24 04:59:09 volumio avahi-daemon[764]: Withdrawing address record for 169.254.13.24 on eth0. Feb 24 04:59:09 volumio avahi-daemon[764]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.13.24. Feb 24 04:59:09 volumio dhcpcd[1064]: eth0: deleting route to 169.254.0.0/16 Feb 24 04:59:09 volumio avahi-daemon[764]: Interface eth0.IPv4 no longer relevant for mDNS. Feb 24 04:59:09 volumio volumio[1075]: info: Discovery: A device disappeared from network Feb 24 04:59:09 volumio volumio[1075]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Feb 24 04:59:09 volumio ifplugd(eth0)[879]: Link beat lost. Feb 24 04:59:14 volumio volumio[1075]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 24 04:59:19 volumio ifplugd(eth0)[879]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Feb 24 04:59:19 volumio dhcpcd[18240]: sending commands to master dhcpcd process Feb 24 04:59:19 volumio dhcpcd[1064]: control command: /sbin/dhcpcd -k eth0 Feb 24 04:59:19 volumio ifplugd(eth0)[879]: client: sending commands to master dhcpcd process Feb 24 04:59:19 volumio dhcpcd[1064]: eth0: removing interface Feb 24 04:59:19 volumio ifplugd(eth0)[879]: Program executed successfully. Feb 24 04:59:32 volumio volumio[1075]: info: Preload queue cleared Feb 24 04:59:32 volumio volumio[1075]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::ClearQueue Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::stPlaybackTimer Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 04:59:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::serviceStop Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:32 volumio volumio[1075]: info: CoreCommandRouter::serviceStop Feb 24 04:59:32 volumio volumio[1075]: info: [1740373172788] ControllerWebradio::stop Feb 24 04:59:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::clearPlayQueue Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 04:59:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Feb 24 04:59:32 volumio volumio[1075]: info: Preload queue cleared Feb 24 04:59:32 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1770054 Feb 24 04:59:32 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 04:59:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Feb 24 04:59:32 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::play index 0 Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::play index undefined Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:32 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Feb 24 04:59:32 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:32 volumio volumio[1075]: info: [1740373172797] ControllerWebradio::clearAddPlayTrack Feb 24 04:59:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 04:59:32 volumio volumio[1075]: info: Feb 24 04:59:32 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:32 volumio volumio[1075]: info: sendMpdCommand stop took 13 milliseconds Feb 24 04:59:32 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:32 volumio volumio[1075]: info: sendMpdCommand stop took 4 milliseconds Feb 24 04:59:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 04:59:32 volumio volumio[1075]: info: Feb 24 04:59:32 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:32 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:32 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Feb 24 04:59:32 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Feb 24 04:59:32 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:32 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1770054" Feb 24 04:59:32 volumio volumio[1075]: error: updateQueue error: null Feb 24 04:59:32 volumio volumio[1075]: info: ------------------------------ 2ms Feb 24 04:59:32 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 04:59:32 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:32 volumio volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 04:59:32 volumio volumio[1075]: info: ------------------------------ 5ms Feb 24 04:59:33 volumio volumio[1075]: info: Feb 24 04:59:33 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:33 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:33 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1770054" took 335 milliseconds Feb 24 04:59:33 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 04:59:33 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Feb 24 04:59:33 volumio volumio[1075]: info: ------------------------------ 1ms Feb 24 04:59:33 volumio volumio[1075]: info: sendMpdCommand play took 1 milliseconds Feb 24 04:59:35 volumio volumio[1075]: info: Feb 24 04:59:35 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:35 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:35 volumio volumio[1075]: info: Feb 24 04:59:35 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:35 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:35 volumio volumio[1075]: info: Feb 24 04:59:35 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:35 volumio volumio[1075]: info: sendMpdCommand status took 26 milliseconds Feb 24 04:59:35 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:35 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:35 volumio volumio[1075]: info: ------------------------------ 5ms Feb 24 04:59:35 volumio volumio[1075]: info: sendMpdCommand status took 3 milliseconds Feb 24 04:59:35 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 04:59:35 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:35 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:35 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:35 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 04:59:35 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 04:59:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:35 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"46 Kbps","isStreaming":false,"title":"113.164.229.226:8035","artist":"VOV1_TNVN1","album":null,"uri":"http://113.164.229.226:8035","trackType":"226:8035"} Feb 24 04:59:35 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 04:59:35 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 04:59:35 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Feb 24 04:59:35 volumio volumio[1075]: info: ------------------------------ 34ms Feb 24 04:59:35 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 04:59:35 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:35 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 04:59:35 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 04:59:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:35 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"46 Kbps","isStreaming":false,"title":"113.164.229.226:8035","artist":"VOV1_TNVN1","album":null,"uri":"http://113.164.229.226:8035","trackType":"226:8035"} Feb 24 04:59:35 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 04:59:35 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 04:59:35 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Feb 24 04:59:35 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Feb 24 04:59:35 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:35 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:35 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 04:59:35 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:35 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:35 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:35 volumio volumio[1075]: info: ------------------------------ 23ms Feb 24 04:59:38 volumio volumio[1075]: info: Preload queue cleared Feb 24 04:59:38 volumio volumio[1075]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::ClearQueue Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::stPlaybackTimer Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:38 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 04:59:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::serviceStop Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:38 volumio volumio[1075]: info: CoreCommandRouter::serviceStop Feb 24 04:59:38 volumio volumio[1075]: info: [1740373178307] ControllerWebradio::stop Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::clearPlayQueue Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 04:59:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Feb 24 04:59:38 volumio volumio[1075]: info: Preload queue cleared Feb 24 04:59:38 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1916740 Feb 24 04:59:38 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 04:59:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Feb 24 04:59:38 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::play index 0 Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::play index undefined Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Feb 24 04:59:38 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:38 volumio volumio[1075]: info: [1740373178319] ControllerWebradio::clearAddPlayTrack Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 04:59:38 volumio volumio[1075]: info: Feb 24 04:59:38 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:38 volumio volumio[1075]: info: sendMpdCommand stop took 16 milliseconds Feb 24 04:59:38 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:38 volumio volumio[1075]: info: sendMpdCommand stop took 3 milliseconds Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 04:59:38 volumio volumio[1075]: info: Feb 24 04:59:38 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:38 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:38 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Feb 24 04:59:38 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1916740" Feb 24 04:59:38 volumio volumio[1075]: error: updateQueue error: null Feb 24 04:59:38 volumio volumio[1075]: info: ------------------------------ 2ms Feb 24 04:59:38 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:38 volumio volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 04:59:38 volumio volumio[1075]: info: ------------------------------ 4ms Feb 24 04:59:38 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1916740" took 315 milliseconds Feb 24 04:59:38 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 04:59:38 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Feb 24 04:59:38 volumio volumio[1075]: info: Feb 24 04:59:38 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:38 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:38 volumio volumio[1075]: info: sendMpdCommand play took 1 milliseconds Feb 24 04:59:38 volumio volumio[1075]: info: ------------------------------ 2ms Feb 24 04:59:39 volumio volumio[1075]: info: Feb 24 04:59:39 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:39 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:39 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:39 volumio volumio[1075]: info: Feb 24 04:59:39 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:39 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:39 volumio volumio[1075]: info: Feb 24 04:59:39 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:39 volumio volumio[1075]: info: sendMpdCommand status took 25 milliseconds Feb 24 04:59:39 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:39 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:39 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:39 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:39 volumio volumio[1075]: info: ------------------------------ 4ms Feb 24 04:59:39 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Feb 24 04:59:39 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 04:59:39 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:39 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:39 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:39 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 04:59:39 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 04:59:39 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:39 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"255 Kbps","isStreaming":false,"title":"Music Freeze Your Mind -","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.135.4:5014","trackType":"4:5014"} Feb 24 04:59:39 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 04:59:39 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 04:59:39 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Feb 24 04:59:39 volumio volumio[1075]: info: ------------------------------ 30ms Feb 24 04:59:39 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 04:59:39 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:39 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 04:59:39 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 04:59:39 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:39 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"255 Kbps","isStreaming":false,"title":"Music Freeze Your Mind -","artist":"COOLfahrenheit | Easy Listening","album":null,"uri":"http://103.253.135.4:5014","trackType":"4:5014"} Feb 24 04:59:39 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 04:59:39 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 04:59:39 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Feb 24 04:59:39 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Feb 24 04:59:39 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 04:59:39 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:39 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 04:59:39 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:39 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:39 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:39 volumio volumio[1075]: info: ------------------------------ 16ms Feb 24 04:59:55 volumio volumio[1075]: info: Preload queue cleared Feb 24 04:59:55 volumio volumio[1075]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::ClearQueue Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::stPlaybackTimer Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:55 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 04:59:55 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::serviceStop Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:55 volumio volumio[1075]: info: CoreCommandRouter::serviceStop Feb 24 04:59:55 volumio volumio[1075]: info: [1740373195415] ControllerWebradio::stop Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::clearPlayQueue Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 04:59:55 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Feb 24 04:59:55 volumio volumio[1075]: info: Preload queue cleared Feb 24 04:59:55 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139 Feb 24 04:59:55 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 04:59:55 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Feb 24 04:59:55 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::play index 0 Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::play index undefined Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Feb 24 04:59:55 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 04:59:55 volumio volumio[1075]: info: [1740373195429] ControllerWebradio::clearAddPlayTrack Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 04:59:55 volumio volumio[1075]: info: Feb 24 04:59:55 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 04:59:55 volumio volumio[1075]: info: sendMpdCommand stop took 17 milliseconds Feb 24 04:59:55 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 04:59:55 volumio volumio[1075]: info: sendMpdCommand stop took 5 milliseconds Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 04:59:55 volumio volumio[1075]: info: Feb 24 04:59:55 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 04:59:55 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 04:59:55 volumio volumio[1075]: info: sendMpdCommand status took 2 milliseconds Feb 24 04:59:55 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139" Feb 24 04:59:55 volumio volumio[1075]: error: updateQueue error: null Feb 24 04:59:55 volumio volumio[1075]: info: ------------------------------ 2ms Feb 24 04:59:55 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 04:59:55 volumio volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 04:59:55 volumio volumio[1075]: info: ------------------------------ 6ms Feb 24 04:59:55 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139" took 337 milliseconds Feb 24 04:59:55 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 04:59:55 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Feb 24 04:59:55 volumio volumio[1075]: info: sendMpdCommand play took 0 milliseconds Feb 24 05:00:04 volumio volumio[1075]: info: Preload queue cleared Feb 24 05:00:04 volumio volumio[1075]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::ClearQueue Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 05:00:04 volumio volumio[1075]: info: CorePlayQueue::clearPlayQueue Feb 24 05:00:04 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 05:00:04 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::addQueueItems Feb 24 05:00:04 volumio volumio[1075]: info: CorePlayQueue::addQueueItems Feb 24 05:00:04 volumio volumio[1075]: info: Preload queue cleared Feb 24 05:00:04 volumio volumio[1075]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139 Feb 24 05:00:04 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 05:00:04 volumio volumio[1075]: info: CoreCommandRouter::volumioPushQueue Feb 24 05:00:04 volumio volumio[1075]: info: CorePlayQueue::saveQueue Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::updateTrackBlock Feb 24 05:00:04 volumio volumio[1075]: info: CorePlayQueue::getTrackBlock Feb 24 05:00:04 volumio volumio[1075]: info: CoreCommandRouter::volumioPlay Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::play index 0 Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::stop Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::play index undefined Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 05:00:04 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 05:00:04 volumio volumio[1075]: info: CoreStateMachine::startPlaybackTimer Feb 24 05:00:04 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 05:00:04 volumio volumio[1075]: info: [1740373204833] ControllerWebradio::clearAddPlayTrack Feb 24 05:00:04 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 05:00:04 volumio volumio[1075]: info: sendMpdCommand stop took 0 milliseconds Feb 24 05:00:04 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 05:00:04 volumio volumio[1075]: info: Feb 24 05:00:04 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 05:00:04 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 05:00:04 volumio volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Feb 24 05:00:04 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139" Feb 24 05:00:04 volumio volumio[1075]: error: updateQueue error: null Feb 24 05:00:04 volumio volumio[1075]: info: ------------------------------ 2ms Feb 24 05:00:05 volumio volumio[1075]: info: Feb 24 05:00:05 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 05:00:05 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 05:00:05 volumio volumio[1075]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139" took 361 milliseconds Feb 24 05:00:05 volumio volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 05:00:05 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Feb 24 05:00:05 volumio volumio[1075]: info: ------------------------------ 2ms Feb 24 05:00:05 volumio volumio[1075]: info: sendMpdCommand play took 1 milliseconds Feb 24 05:00:06 volumio volumio[1075]: info: Feb 24 05:00:06 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 05:00:06 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 05:00:06 volumio volumio[1075]: info: Feb 24 05:00:06 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 05:00:06 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 05:00:06 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 05:00:06 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Feb 24 05:00:06 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 05:00:06 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 05:00:06 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 05:00:06 volumio volumio[1075]: 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":"chilloutlounge_128","artist":"1.FM - Chillout Lounge (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Feb 24 05:00:06 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Feb 24 05:00:06 volumio volumio[1075]: info: ------------------------------ 6ms Feb 24 05:00:06 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 05:00:06 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 05:00:06 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 05:00:06 volumio volumio[1075]: 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":"chilloutlounge_128","artist":"1.FM - Chillout Lounge (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Feb 24 05:00:06 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Feb 24 05:00:06 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 05:00:06 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 05:00:06 volumio volumio[1075]: info: ------------------------------ 22ms Feb 24 05:00:06 volumio volumio[1075]: info: Feb 24 05:00:06 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 05:00:06 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 05:00:06 volumio volumio[1075]: info: Feb 24 05:00:06 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 05:00:06 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 05:00:06 volumio volumio[1075]: info: ------------------------------ 2ms Feb 24 05:00:06 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 05:00:06 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 05:00:06 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 05:00:06 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 05:00:06 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 05:00:06 volumio volumio[1075]: 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":"192 Kbps","isStreaming":false,"title":"Rollercone - Daydreaming","artist":"1.FM - Chillout Lounge Radio","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Feb 24 05:00:06 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Feb 24 05:00:06 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 05:00:06 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 05:00:06 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 05:00:06 volumio volumio[1075]: info: ------------------------------ 16ms Feb 24 05:00:12 volumio kernel: atl1c 0000:07:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Feb 24 05:00:13 volumio ifplugd(eth0)[879]: Link beat detected. Feb 24 05:00:13 volumio ifplugd(eth0)[879]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 24 05:00:13 volumio dhcpcd[18422]: sending commands to master dhcpcd process Feb 24 05:00:13 volumio dhcpcd[1064]: control command: /sbin/dhcpcd eth0 Feb 24 05:00:13 volumio ifplugd(eth0)[879]: client: sending commands to master dhcpcd process Feb 24 05:00:13 volumio dhcpcd[1064]: eth0: carrier acquired Feb 24 05:00:13 volumio dhcpcd[1064]: eth0: IAID f4:0e:5e:be Feb 24 05:00:13 volumio ifplugd(eth0)[879]: Program executed successfully. Feb 24 05:00:14 volumio dhcpcd[1064]: eth0: soliciting a DHCP lease Feb 24 05:00:14 volumio dhcpcd[1064]: eth0: soliciting an IPv6 router Feb 24 05:00:19 volumio dhcpcd[1064]: eth0: probing for an IPv4LL address Feb 24 05:00:22 volumio volumio[1075]: info: Feb 24 05:00:22 volumio volumio[1075]: ---------------------------- MPD announces system playlist update Feb 24 05:00:22 volumio volumio[1075]: info: Ignoring MPD Status Update Feb 24 05:00:22 volumio volumio[1075]: info: Feb 24 05:00:22 volumio volumio[1075]: ---------------------------- MPD announces state update: player Feb 24 05:00:22 volumio volumio[1075]: info: ControllerMpd::getState Feb 24 05:00:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Feb 24 05:00:22 volumio volumio[1075]: info: ------------------------------ 1ms Feb 24 05:00:22 volumio volumio[1075]: info: sendMpdCommand status took 1 milliseconds Feb 24 05:00:22 volumio volumio[1075]: verbose: ControllerMpd::parseState Feb 24 05:00:22 volumio volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 05:00:22 volumio volumio[1075]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 05:00:22 volumio volumio[1075]: verbose: ControllerMpd::parseTrackInfo Feb 24 05:00:22 volumio volumio[1075]: info: ControllerMpd::pushState Feb 24 05:00:22 volumio volumio[1075]: info: CoreCommandRouter::servicePushState Feb 24 05:00:22 volumio volumio[1075]: info: CorePlayQueue::getTrack 0 Feb 24 05:00:22 volumio volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Isan - No. 1 (Lent Et Douloureux)","artist":"1.FM - Chillout Lounge Radio","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Feb 24 05:00:22 volumio volumio[1075]: verbose: CURRENT POSITION 0 Feb 24 05:00:22 volumio volumio[1075]: info: CoreStateMachine::syncState stateService play Feb 24 05:00:22 volumio volumio[1075]: info: CoreStateMachine::syncState currentStatus play Feb 24 05:00:22 volumio volumio[1075]: info: Received an update from plugin. extracting info from payload Feb 24 05:00:22 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 05:00:22 volumio volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 05:00:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 05:00:22 volumio volumio[1075]: info: CoreStateMachine::pushState Feb 24 05:00:22 volumio volumio[1075]: info: CoreCommandRouter::volumioPushState Feb 24 05:00:22 volumio volumio[1075]: info: ------------------------------ 9ms Feb 24 05:00:23 volumio kernel: atl1c 0000:07:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Feb 24 05:00:23 volumio dhcpcd[1064]: eth0: using IPv4LL address 169.254.13.24 Feb 24 05:00:23 volumio dhcpcd[1064]: eth0: adding route to 169.254.0.0/16 Feb 24 05:00:23 volumio avahi-daemon[764]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.13.24. Feb 24 05:00:23 volumio avahi-daemon[764]: New relevant interface eth0.IPv4 for mDNS. Feb 24 05:00:23 volumio avahi-daemon[764]: Registering new address record for 169.254.13.24 on eth0.IPv4. Feb 24 05:00:24 volumio volumio[1075]: info: Discovery: adding 934313e7-25dc-478d-bb3d-5179482d38dc Feb 24 05:00:24 volumio volumio[1075]: info: Discovery: Found device Volumio Feb 24 05:00:24 volumio volumio[1075]: info: CoreCommandRouter::volumioGetState Feb 24 05:00:25 volumio ntpd[6185]: Listen normally on 8 eth0 169.254.13.24:123 Feb 24 05:00:25 volumio ntpd[6185]: new interface(s) found: waking up resolver Feb 24 05:00:25 volumio volumio[1075]: info: Volumio Network Manager: Network status updated: 3 Feb 24 05:00:29 volumio kernel: atl1c 0000:07:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Feb 24 05:00:41 volumio dhcpcd[1064]: eth0: carrier lost Feb 24 05:00:41 volumio dhcpcd[1064]: eth0: deleting route to 169.254.0.0/16 Feb 24 05:00:41 volumio avahi-daemon[764]: Withdrawing address record for 169.254.13.24 on eth0. Feb 24 05:00:41 volumio avahi-daemon[764]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.13.24. Feb 24 05:00:41 volumio avahi-daemon[764]: Interface eth0.IPv4 no longer relevant for mDNS. Feb 24 05:00:41 volumio volumio[1075]: info: Discovery: A device disappeared from network Feb 24 05:00:41 volumio volumio[1075]: info: Discovery: Device volumio disappeared from network Feb 24 05:00:41 volumio ifplugd(eth0)[879]: Link beat lost. Feb 24 05:00:42 volumio ntpd[6185]: Deleting interface #8 eth0, 169.254.13.24#123, interface stats: received=0, sent=0, dropped=0, active_time=17 secs Feb 24 05:00:45 volumio volumio[1075]: info: Volumio Network Manager: Network status updated: 2 Feb 24 05:00:50 volumio volumio[1075]: info: CoreCommandRouter::volumioGetState Feb 24 05:00:51 volumio volumio[1075]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 05:00:51 volumio volumio[1075]: Error: connect ETIMEDOUT 108.160.167.147:80 Feb 24 05:00:51 volumio volumio[1075]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 24 05:00:51 volumio volumio[1075]: errno: -110, Feb 24 05:00:51 volumio volumio[1075]: code: 'ETIMEDOUT', Feb 24 05:00:51 volumio volumio[1075]: syscall: 'connect', Feb 24 05:00:51 volumio volumio[1075]: address: '108.160.167.147', Feb 24 05:00:51 volumio volumio[1075]: port: 80 Feb 24 05:00:51 volumio volumio[1075]: } Feb 24 05:00:51 volumio volumio[1075]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 05:00:51 volumio ifplugd(eth0)[879]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Feb 24 05:00:51 volumio dhcpcd[18646]: sending commands to master dhcpcd process Feb 24 05:00:51 volumio dhcpcd[1064]: control command: /sbin/dhcpcd -k eth0 Feb 24 05:00:51 volumio dhcpcd[1064]: eth0: removing interface Feb 24 05:00:51 volumio ifplugd(eth0)[879]: client: sending commands to master dhcpcd process Feb 24 05:00:52 volumio ifplugd(eth0)[879]: Program executed successfully. Feb 24 05:00:52 volumio kernel: atl1c 0000:07:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Feb 24 05:00:52 volumio ifplugd(eth0)[879]: Link beat detected. Feb 24 05:00:52 volumio sudo[18674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 04:59 Feb 24 05:00:52 volumio sudo[18674]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"