-- 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"