-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Thu 2024-07-25 15:14:50 EDT. -- Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d2b79e0 does not match aorg 0000000000.00000000 from server@45.55.58.103 xmt 0xea4d2247.6e717b67 Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d2d602c does not match aorg 0000000000.00000000 from server@23.94.200.138 xmt 0xea4d2247.70dde59f Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d2a21d9 does not match aorg 0000000000.00000000 from server@73.234.118.31 xmt 0xea4d2247.70c1e50f Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d246402 does not match aorg 0000000000.00000000 from server@74.208.117.38 xmt 0xea4d2247.72b2c081 Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d1b3978 does not match aorg 0000000000.00000000 from server@23.168.24.210 xmt 0xea4d2247.753d4f9b Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d225d18 does not match aorg 0000000000.00000000 from server@216.229.4.66 xmt 0xea4d2247.750d63d7 Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d275c3d does not match aorg 0000000000.00000000 from server@66.220.10.2 xmt 0xea4d2247.75f06e6c Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d25e848 does not match aorg 0000000000.00000000 from server@5.78.62.36 xmt 0xea4d2247.7890b0cb Jul 25 15:13:11 pecanpiplus ntpd[802]: receive: Unexpected origin timestamp 0xea4d2218.6d28bbe3 does not match aorg 0000000000.00000000 from server@135.148.100.14 xmt 0xea4d2247.8637d143 Jul 25 15:13:13 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 25 15:13:13 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 25 15:13:13 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 25 15:13:13 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 25 15:13:13 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 25 15:13:13 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioGetBrowseSources Jul 25 15:13:13 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 25 15:13:15 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 25 15:13:15 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 25 15:13:23 pecanpiplus volumio[841]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 25 15:13:23 pecanpiplus volumio[841]: info: Received Get System Version Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 25 15:13:23 pecanpiplus volumio[841]: info: Received Get System Info Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 15:13:23 pecanpiplus volumio[841]: info: Discovery: Getting this device information Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioGetState Jul 25 15:13:23 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 25 15:13:23 pecanpiplus volumio[841]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 25 15:13:23 pecanpiplus volumio[841]: info: Received Get System Version Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 25 15:13:23 pecanpiplus volumio[841]: info: Received Get System Info Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 15:13:23 pecanpiplus volumio[841]: info: Discovery: Getting this device information Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioGetState Jul 25 15:13:23 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:13:23 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 15:13:34 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioGetState Jul 25 15:13:34 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:13:48 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioGetQueue Jul 25 15:13:48 pecanpiplus volumio[841]: info: CoreStateMachine::getQueue Jul 25 15:13:48 pecanpiplus volumio[841]: info: CorePlayQueue::getQueue Jul 25 15:13:49 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:13:49 pecanpiplus volumio[841]: info: Preloading song: mnt/USB/MUSIC/Ella Fitzgerald/Clap Hands Here Comes Charlie!/09-This Year's Kisses.flac Jul 25 15:13:49 pecanpiplus volumio[841]: info: Exploding uri mnt/USB/MUSIC/Ella Fitzgerald/Clap Hands Here Comes Charlie!/09-This Year's Kisses.flac in service mpd Jul 25 15:13:49 pecanpiplus volumio[841]: error: scanFolder - failure to stat '/mnt/USB/MUSIC/Ella Fitzgerald/Clap Hands Here Comes Charlie!/09-This Year's Kisses.flac' Jul 25 15:13:56 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::ClearQueue Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::stop Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:13:56 pecanpiplus volumio[841]: info: CorePlayQueue::clearPlayQueue Jul 25 15:13:56 pecanpiplus volumio[841]: info: CorePlayQueue::saveQueue Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushQueue Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::addQueueItems Jul 25 15:13:56 pecanpiplus volumio[841]: info: CorePlayQueue::addQueueItems Jul 25 15:13:56 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:13:56 pecanpiplus volumio[841]: info: Adding Item to queue: mnt/USB/MUSIC/Ella Fitzgerald/Clap Hands Here Comes Charlie!/09-This Year's Kisses.flac Jul 25 15:13:56 pecanpiplus volumio[841]: info: Using cached record of: mnt/USB/MUSIC/Ella Fitzgerald/Clap Hands Here Comes Charlie!/09-This Year's Kisses.flac Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushQueue Jul 25 15:13:56 pecanpiplus volumio[841]: info: CorePlayQueue::saveQueue Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::updateTrackBlock Jul 25 15:13:56 pecanpiplus volumio[841]: info: CorePlayQueue::getTrackBlock Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPlay Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::play index 0 Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::stop Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::play index undefined Jul 25 15:13:56 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:13:56 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:13:58 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::ClearQueue Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::stop Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:13:58 pecanpiplus volumio[841]: info: CorePlayQueue::clearPlayQueue Jul 25 15:13:58 pecanpiplus volumio[841]: info: CorePlayQueue::saveQueue Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushQueue Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::addQueueItems Jul 25 15:13:58 pecanpiplus volumio[841]: info: CorePlayQueue::addQueueItems Jul 25 15:13:58 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:13:58 pecanpiplus volumio[841]: info: Adding Item to queue: mnt/USB/MUSIC/Ella Fitzgerald/Clap Hands Here Comes Charlie!/09-This Year's Kisses.flac Jul 25 15:13:58 pecanpiplus volumio[841]: info: Using cached record of: mnt/USB/MUSIC/Ella Fitzgerald/Clap Hands Here Comes Charlie!/09-This Year's Kisses.flac Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushQueue Jul 25 15:13:58 pecanpiplus volumio[841]: info: CorePlayQueue::saveQueue Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::updateTrackBlock Jul 25 15:13:58 pecanpiplus volumio[841]: info: CorePlayQueue::getTrackBlock Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPlay Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::play index 0 Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::stop Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::play index undefined Jul 25 15:13:58 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:13:58 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:10 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 25 15:14:10 pecanpiplus volumio[841]: info: CURURI: music-library Jul 25 15:14:10 pecanpiplus volumio[841]: error: Failed LSINFO: null Jul 25 15:14:10 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:14:27 pecanpiplus kernel: usb 1-1.4: new high-speed USB device number 4 using dwc2 Jul 25 15:14:27 pecanpiplus kernel: usb 1-1.4: New USB device found, idVendor=0718, idProduct=0723, bcdDevice= 1.00 Jul 25 15:14:27 pecanpiplus kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 25 15:14:27 pecanpiplus kernel: usb 1-1.4: Product: Nano Pro Jul 25 15:14:27 pecanpiplus kernel: usb 1-1.4: Manufacturer: Imation Jul 25 15:14:27 pecanpiplus kernel: usb 1-1.4: SerialNumber: 070344B061F92D71 Jul 25 15:14:27 pecanpiplus kernel: usb-storage 1-1.4:1.0: USB Mass Storage device detected Jul 25 15:14:27 pecanpiplus kernel: scsi host0: usb-storage 1-1.4:1.0 Jul 25 15:14:28 pecanpiplus kernel: scsi 0:0:0:0: Direct-Access Imation Nano Pro PMAP PQ: 0 ANSI: 6 Jul 25 15:14:28 pecanpiplus kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jul 25 15:14:29 pecanpiplus kernel: sd 0:0:0:0: [sda] 15122432 512-byte logical blocks: (7.74 GB/7.21 GiB) Jul 25 15:14:29 pecanpiplus kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 25 15:14:29 pecanpiplus kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00 Jul 25 15:14:29 pecanpiplus kernel: sd 0:0:0:0: [sda] No Caching mode page found Jul 25 15:14:29 pecanpiplus kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Jul 25 15:14:29 pecanpiplus kernel: sda: sda1 Jul 25 15:14:29 pecanpiplus kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Jul 25 15:14:29 pecanpiplus volumio[841]: info: Mounting Device FAB2-85C1 Jul 25 15:14:29 pecanpiplus sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/FAB2-85C1 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 25 15:14:29 pecanpiplus sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 15:14:29 pecanpiplus kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 25 15:14:29 pecanpiplus sudo[1717]: pam_unix(sudo:session): session closed for user root Jul 25 15:14:29 pecanpiplus kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 25 15:14:29 pecanpiplus volumio[841]: info: Scanning new location : "USB/FAB2-85C1" Jul 25 15:14:29 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:29 pecanpiplus volumio[841]: info: Jul 25 15:14:29 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: update Jul 25 15:14:29 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:29 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:29 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:29 pecanpiplus volumio[841]: info: Jul 25 15:14:29 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: update Jul 25 15:14:29 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:29 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:29 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:29 pecanpiplus volumio[841]: info: Jul 25 15:14:29 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: update Jul 25 15:14:29 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:29 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:29 pecanpiplus volumio[841]: info: sendMpdCommand status took 25 milliseconds Jul 25 15:14:29 pecanpiplus volumio[841]: info: sendMpdCommand status took 20 milliseconds Jul 25 15:14:29 pecanpiplus volumio[841]: info: sendMpdCommand status took 18 milliseconds Jul 25 15:14:29 pecanpiplus volumio[841]: info: sendMpdCommand status took 15 milliseconds Jul 25 15:14:29 pecanpiplus volumio[841]: info: sendMpdCommand status took 12 milliseconds Jul 25 15:14:29 pecanpiplus volumio[841]: info: sendMpdCommand status took 11 milliseconds Jul 25 15:14:29 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:30 pecanpiplus volumio[841]: info: Command Router : Notfying DB Updatetrue Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:30 pecanpiplus volumio[841]: info: Command Router : Notfying DB Updatetrue Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::Close All Modals sent Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:30 pecanpiplus volumio[841]: info: Command Router : Notfying DB Updatetrue Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::Close All Modals sent Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:30 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: No code Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: No code Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:30 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: No code Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:30 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:30 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:30 pecanpiplus volumio[841]: info: ------------------------------ 450ms Jul 25 15:14:30 pecanpiplus volumio[841]: info: ------------------------------ 444ms Jul 25 15:14:30 pecanpiplus volumio[841]: info: ------------------------------ 443ms Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:30 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:31 pecanpiplus volumio[841]: info: MPD Database updated - AlbumList cache refreshed Jul 25 15:14:31 pecanpiplus volumio[841]: info: Jul 25 15:14:31 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: database Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: Jul 25 15:14:31 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: update Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: MPD Database updated - AlbumList cache refreshed Jul 25 15:14:31 pecanpiplus volumio[841]: info: Jul 25 15:14:31 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: database Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: Jul 25 15:14:31 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: update Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: MPD Database updated - AlbumList cache refreshed Jul 25 15:14:31 pecanpiplus volumio[841]: info: Jul 25 15:14:31 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: database Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: Jul 25 15:14:31 pecanpiplus volumio[841]: ---------------------------- MPD announces state update: update Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::getState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::sendMpdCommand status Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 33 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 32 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 32 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 29 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 28 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 26 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 22 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 22 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: info: sendMpdCommand status took 21 milliseconds Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: info: Command Router : Notfying DB Updatefalse Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::Close All Modals sent Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: info: Command Router : Notfying DB Updatefalse Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::Close All Modals sent Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: info: Command Router : Notfying DB Updatefalse Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::Close All Modals sent Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: ControllerMpd::parseState Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: No code Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: No code Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: No code Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: No code Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: No code Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: ControllerMpd::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::servicePushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 25 15:14:31 pecanpiplus volumio[841]: verbose: CURRENT POSITION 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState stateService stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::syncState currentStatus stop Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: No code Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreStateMachine::pushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: CorePlayQueue::getTrack 0 Jul 25 15:14:31 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushState Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output update for this device Jul 25 15:14:31 pecanpiplus volumio[841]: info: MRS: Pushing multiroomSync output Jul 25 15:14:31 pecanpiplus volumio[841]: info: ------------------------------ 477ms Jul 25 15:14:31 pecanpiplus volumio[841]: info: ------------------------------ 478ms Jul 25 15:14:31 pecanpiplus volumio[841]: info: ------------------------------ 475ms Jul 25 15:14:31 pecanpiplus volumio[841]: info: ------------------------------ 476ms Jul 25 15:14:31 pecanpiplus volumio[841]: info: ------------------------------ 472ms Jul 25 15:14:31 pecanpiplus volumio[841]: info: ------------------------------ 473ms Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:32 pecanpiplus volumio[841]: info: Updating RAAT Signal Path Jul 25 15:14:34 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 25 15:14:34 pecanpiplus volumio[841]: info: CURURI: music-library Jul 25 15:14:34 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:14:36 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 25 15:14:36 pecanpiplus volumio[841]: info: CURURI: music-library/USB Jul 25 15:14:36 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:14:37 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 25 15:14:37 pecanpiplus volumio[841]: info: CURURI: music-library/USB/FAB2-85C1 Jul 25 15:14:37 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:14:38 pecanpiplus volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 25 15:14:38 pecanpiplus volumio[841]: info: CURURI: music-library/USB/FAB2-85C1/Demo Room Jul 25 15:14:38 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:14:38 pecanpiplus volumio[841]: info: Preloading song: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-176k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Preloading song: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-192k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Preloading song: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-44k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Preloading song: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-48k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Preloading song: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-88k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Preloading song: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-96k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-176k.wav in service mpd Jul 25 15:14:38 pecanpiplus volumio[841]: info: ALBUMART /albumart?cacheid=178&web=//extralarge&path=%2Fmnt%2FUSB%2FFAB2-85C1%2FDemo%20Room%2FCorrectionLR-176k.wav&metadata=false Jul 25 15:14:38 pecanpiplus volumio[841]: info: URI /mnt/USB/FAB2-85C1/Demo Room/CorrectionLR-176k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-192k.wav in service mpd Jul 25 15:14:38 pecanpiplus volumio[841]: info: ALBUMART /albumart?cacheid=178&web=//extralarge&path=%2Fmnt%2FUSB%2FFAB2-85C1%2FDemo%20Room%2FCorrectionLR-192k.wav&metadata=false Jul 25 15:14:38 pecanpiplus volumio[841]: info: URI /mnt/USB/FAB2-85C1/Demo Room/CorrectionLR-192k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-44k.wav in service mpd Jul 25 15:14:38 pecanpiplus volumio[841]: info: ALBUMART /albumart?cacheid=178&web=//extralarge&path=%2Fmnt%2FUSB%2FFAB2-85C1%2FDemo%20Room%2FCorrectionLR-44k.wav&metadata=false Jul 25 15:14:38 pecanpiplus volumio[841]: info: URI /mnt/USB/FAB2-85C1/Demo Room/CorrectionLR-44k.wav Jul 25 15:14:38 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-48k.wav in service mpd Jul 25 15:14:38 pecanpiplus volumio[841]: info: ALBUMART /albumart?cacheid=178&web=//extralarge&path=%2Fmnt%2FUSB%2FFAB2-85C1%2FDemo%20Room%2FCorrectionLR-48k.wav&metadata=false Jul 25 15:14:38 pecanpiplus volumio[841]: info: URI /mnt/USB/FAB2-85C1/Demo Room/CorrectionLR-48k.wav Jul 25 15:14:39 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-88k.wav in service mpd Jul 25 15:14:39 pecanpiplus volumio[841]: info: ALBUMART /albumart?cacheid=178&web=//extralarge&path=%2Fmnt%2FUSB%2FFAB2-85C1%2FDemo%20Room%2FCorrectionLR-88k.wav&metadata=false Jul 25 15:14:39 pecanpiplus volumio[841]: info: URI /mnt/USB/FAB2-85C1/Demo Room/CorrectionLR-88k.wav Jul 25 15:14:39 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-96k.wav in service mpd Jul 25 15:14:39 pecanpiplus volumio[841]: info: ALBUMART /albumart?cacheid=178&web=//extralarge&path=%2Fmnt%2FUSB%2FFAB2-85C1%2FDemo%20Room%2FCorrectionLR-96k.wav&metadata=false Jul 25 15:14:39 pecanpiplus volumio[841]: info: URI /mnt/USB/FAB2-85C1/Demo Room/CorrectionLR-96k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:14:48 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 15:14:48 pecanpiplus volumio[841]: info: CoreStateMachine::ClearQueue Jul 25 15:14:48 pecanpiplus volumio[841]: info: CoreStateMachine::stop Jul 25 15:14:48 pecanpiplus volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 15:14:48 pecanpiplus volumio[841]: info: CorePlayQueue::clearPlayQueue Jul 25 15:14:48 pecanpiplus volumio[841]: info: CorePlayQueue::saveQueue Jul 25 15:14:48 pecanpiplus volumio[841]: info: CoreCommandRouter::volumioPushQueue Jul 25 15:14:48 pecanpiplus volumio[841]: info: CoreStateMachine::addQueueItems Jul 25 15:14:48 pecanpiplus volumio[841]: info: CorePlayQueue::addQueueItems Jul 25 15:14:48 pecanpiplus volumio[841]: info: Preload queue cleared Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-176k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Using cached record of: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-176k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-192k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Using cached record of: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-192k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-44k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Using cached record of: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-44k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-48k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Using cached record of: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-48k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-88k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Using cached record of: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-88k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-96k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Using cached record of: music-library/USB/FAB2-85C1/Demo Room/CorrectionLR-96k.wav Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/David_Filter.zip Jul 25 15:14:48 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/David_Filter.zip in service mpd Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/Leo_Filter_2.zip Jul 25 15:14:48 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/Leo_Filter_2.zip in service mpd Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/Leo_Filter.zip Jul 25 15:14:48 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/Leo_Filter.zip in service mpd Jul 25 15:14:48 pecanpiplus volumio[841]: info: Adding Item to queue: music-library/USB/FAB2-85C1/Demo Room/Log Sweeps Jul 25 15:14:48 pecanpiplus volumio[841]: info: Exploding uri music-library/USB/FAB2-85C1/Demo Room/Log Sweeps in service mpd Jul 25 15:14:48 pecanpiplus volumio[841]: info: ALBUMART /albumart?cacheid=178&web=//extralarge&path=%2Fmnt%2FUSB%2FFAB2-85C1%2FDemo%20Room%2FDavid_Filter.zip&metadata=false Jul 25 15:14:48 pecanpiplus volumio[841]: info: URI /mnt/USB/FAB2-85C1/Demo Room/David_Filter.zip Jul 25 15:14:48 pecanpiplus volumio[841]: info: ALBUMART /albumart?cacheid=178&web=//extralarge&path=%2Fmnt%2FUSB%2FFAB2-85C1%2FDemo%20Room%2FDavid_Filter.zip&metadata=false Jul 25 15:14:48 pecanpiplus volumio[841]: info: URI /mnt/USB/FAB2-85C1/Demo Room/David_Filter.zip Jul 25 15:14:48 pecanpiplus volumio[841]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 15:14:48 pecanpiplus volumio[841]: Error: Unable to resolve or reject the same promise twice Jul 25 15:14:48 pecanpiplus volumio[841]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 25 15:14:48 pecanpiplus volumio[841]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Jul 25 15:14:48 pecanpiplus volumio[841]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 25 15:14:48 pecanpiplus volumio[841]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 25 15:14:48 pecanpiplus volumio[841]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 25 15:14:48 pecanpiplus volumio[841]: at Socket.emit (events.js:315:20) Jul 25 15:14:48 pecanpiplus volumio[841]: at addChunk (internal/streams/readable.js:309:12) Jul 25 15:14:48 pecanpiplus volumio[841]: at readableAddChunk (internal/streams/readable.js:280:11) Jul 25 15:14:48 pecanpiplus volumio[841]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jul 25 15:14:48 pecanpiplus volumio[841]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 25 15:14:48 pecanpiplus volumio[841]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 15:14:50 pecanpiplus sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-25 15:13 Jul 25 15:14:50 pecanpiplus sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="orchard" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 25 Jul 2024 06:12:42 PM CEST" VOLUMIO_VERSION="3.746" VOLUMIO_HARDWARE="pi-kiosk" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Orchard Audio PecanPi+ Streamer Premium" VOLUMIO_VENDOR="Orchard Audio" VOLUMIO_MODEL="PecanPi+ Streamer Premium" VOLUMIO_HASH="a3457a7140023f680c85cb9e1ec1973d"