-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Thu 2024-07-04 19:50:56 EEST. --
Jul 04 19:49:05 volumio go-librespot[1245]: time="2024-07-04T19:49:05+03:00" level=debug msg="fetched chunk 11/14, size: 524288"
Jul 04 19:49:18 volumio go-librespot[1245]: time="2024-07-04T19:49:18+03:00" level=debug msg="fetched chunk 12/14, size: 524288"
Jul 04 19:49:22 volumio volumio[1046]: info: VolumeController::SetAlsaVolume100
Jul 04 19:49:22 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:49:22 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:22 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 04 19:49:22 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:49:22 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 04 19:49:22 volumio volumio[1046]: SPOTIFY: SPOTIFY VOLUME 85
Jul 04 19:49:22 volumio volumio[1046]: SPOTIFY: VOLUMIO VOLUME 100
Jul 04 19:49:22 volumio volumio[1046]: SPOTIFY: DELTA VOLUME ENOUGH: true
Jul 04 19:49:22 volumio volumio[1046]: info: Setting Spotify Volume from Volumio: 100
Jul 04 19:49:23 volumio volumio[1046]: info: VolumeController::SetAlsaVolume0
Jul 04 19:49:23 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:49:23 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:23 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 04 19:49:23 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:49:24 volumio volumio[1046]: SPOTIFY: SETTING SPOTIFY VOLUME 100
Jul 04 19:49:24 volumio volumio[1046]: info: Sending Spotify command with payload to local API: /player/volume
Jul 04 19:49:24 volumio go-librespot[1245]: time="2024-07-04T19:49:24+03:00" level=debug msg="update volume to 65535/65535"
Jul 04 19:49:24 volumio go-librespot[1245]: time="2024-07-04T19:49:24+03:00" level=debug msg="put connect state because VOLUME_CHANGED"
Jul 04 19:49:24 volumio go-librespot[1245]: time="2024-07-04T19:49:24+03:00" level=trace msg="emitting websocket event: volume"
Jul 04 19:49:24 volumio volumio[1046]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}}
Jul 04 19:49:24 volumio volumio[1046]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100
Jul 04 19:49:24 volumio volumio[1046]: info: VolumeController::SetAlsaVolume49
Jul 04 19:49:24 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:49:24 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:24 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 04 19:49:24 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:49:24 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:49:24 volumio volumio[1046]: SPOTIFY: SPOTIFY VOLUME 100
Jul 04 19:49:24 volumio volumio[1046]: SPOTIFY: VOLUMIO VOLUME 49
Jul 04 19:49:24 volumio volumio[1046]: SPOTIFY: DELTA VOLUME ENOUGH: true
Jul 04 19:49:24 volumio volumio[1046]: info: Setting Spotify Volume from Volumio: 49
Jul 04 19:49:26 volumio volumio[1046]: SPOTIFY: SETTING SPOTIFY VOLUME 49
Jul 04 19:49:26 volumio volumio[1046]: info: Sending Spotify command with payload to local API: /player/volume
Jul 04 19:49:26 volumio go-librespot[1245]: time="2024-07-04T19:49:26+03:00" level=debug msg="update volume to 32112/65535"
Jul 04 19:49:26 volumio go-librespot[1245]: time="2024-07-04T19:49:26+03:00" level=debug msg="put connect state because VOLUME_CHANGED"
Jul 04 19:49:26 volumio go-librespot[1245]: time="2024-07-04T19:49:26+03:00" level=trace msg="emitting websocket event: volume"
Jul 04 19:49:26 volumio volumio[1046]: SPOTIFY: received: {"type":"volume","data":{"value":49,"max":100}}
Jul 04 19:49:26 volumio volumio[1046]: SPOTIFY: RECEIVED SPOTIFY VOLUME 49
Jul 04 19:49:27 volumio volumio[1046]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:27 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:27 volumio volumio[1046]: info: Received Get System Info
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 04 19:49:27 volumio volumio[1046]: info: Discovery: Getting this device information
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:27 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:27 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:27 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:27 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetQueue
Jul 04 19:49:27 volumio volumio[1046]: info: CoreStateMachine::getQueue
Jul 04 19:49:27 volumio volumio[1046]: info: CorePlayQueue::getQueue
Jul 04 19:49:27 volumio volumio[1046]: info: Listing playlists
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 04 19:49:27 volumio volumio[1046]: info: Received Get System Info
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 04 19:49:27 volumio volumio[1046]: info: Discovery: Getting this device information
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:27 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:27 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:49:27 volumio volumio[1046]: info: FusionDsp - IP adress is ---------------------------192.168.0.106
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 04 19:49:27 volumio volumio[1046]: info: FusionDsp - IP adress is ---------------------------192.168.0.106
Jul 04 19:49:27 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:49:27 volumio volumio[1046]: info: FusionDsp - IP adress is ---------------------------192.168.0.106
Jul 04 19:49:27 volumio volumio[1046]: info: FusionDsp - IP adress is ---------------------------192.168.0.106
Jul 04 19:49:30 volumio go-librespot[1245]: time="2024-07-04T19:49:30+03:00" level=debug msg="fetched chunk 13/14, size: 524288"
Jul 04 19:49:30 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:30 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:34 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 04 19:49:34 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jul 04 19:49:34 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:49:34 volumio volumio[1046]: info: FusionDsp - IP adress is ---------------------------192.168.0.106
Jul 04 19:49:34 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:49:34 volumio volumio[1046]: info: FusionDsp - IP adress is ---------------------------192.168.0.106
Jul 04 19:49:35 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 04 19:49:37 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jul 04 19:49:39 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 04 19:49:43 volumio go-librespot[1245]: time="2024-07-04T19:49:43+03:00" level=debug msg="fetched chunk 14/14, size: 393649"
Jul 04 19:49:46 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:49:46 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:50 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jul 04 19:49:50 volumio volumio[1046]: info: Preload queue cleared
Jul 04 19:49:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jul 04 19:49:52 volumio volumio[1046]: info: Preload queue cleared
Jul 04 19:49:54 volumio volumio[1046]: info: Preload queue cleared
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::ClearQueue
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::stop
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::stPlaybackTimer
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::serviceStop
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::serviceStop
Jul 04 19:49:54 volumio volumio[1046]: info: Spotify Stop
Jul 04 19:49:54 volumio volumio[1046]: SPOTIFY: SPOTIFY STOP
Jul 04 19:49:54 volumio volumio[1046]: SPOTIFY: {"status":"play","position":0,"title":"Please Please Please","artist":"Sabrina Carpenter","album":"Please Please Please","albumart":"https://i.scdn.co/image/ab67616d0000b273de84adf0e48248ea2d769c3e","uri":"spotify:track:5N3hjp1WNayUPZrA8kJmJP","trackType":"spotify","codec":"ogg","seek":116989,"duration":186,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":false,"repeatSingle":false,"consume":false,"volume":49,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"}
Jul 04 19:49:54 volumio volumio[1046]: info: Sending Spotify command to local API: /player/pause
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::clearPlayQueue
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::saveQueue
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::volumioPushQueue
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::addQueueItems
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::addQueueItems
Jul 04 19:49:54 volumio volumio[1046]: info: Preload queue cleared
Jul 04 19:49:54 volumio volumio[1046]: info: Adding Item to queue: https://cast.mediaonline.net.ua:443/avtoradio
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::volumioPushQueue
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::saveQueue
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::volumioPlay
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::play index 0
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::stop
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::play index undefined
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:54 volumio volumio[1046]: info: CoreStateMachine::startPlaybackTimer
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:54 volumio volumio[1046]: info: [1720111794330] ControllerWebradio::clearAddPlayTrack
Jul 04 19:49:54 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop
Jul 04 19:49:54 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:49:54 volumio volumio[1046]: info: sendMpdCommand stop took 4 milliseconds
Jul 04 19:49:54 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear
Jul 04 19:49:54 volumio volumio[1046]: info:
Jul 04 19:49:54 volumio volumio[1046]: ---------------------------- MPD announces system playlist update
Jul 04 19:49:54 volumio volumio[1046]: info: Ignoring MPD Status Update
Jul 04 19:49:54 volumio volumio[1046]: info: sendMpdCommand clear took 1 milliseconds
Jul 04 19:49:54 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand load "https://cast.mediaonline.net.ua:443/avtoradio"
Jul 04 19:49:54 volumio volumio[1046]: error: updateQueue error: null
Jul 04 19:49:54 volumio volumio[1046]: info: ------------------------------ 2ms
Jul 04 19:49:54 volumio go-librespot[1245]: time="2024-07-04T19:49:54+03:00" level=debug msg="pause track at 148034ms"
Jul 04 19:49:54 volumio go-librespot[1245]: time="2024-07-04T19:49:54+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 04 19:49:54 volumio go-librespot[1245]: time="2024-07-04T19:49:54+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 04 19:49:54 volumio go-librespot[1245]: time="2024-07-04T19:49:54+03:00" level=trace msg="emitting websocket event: paused"
Jul 04 19:49:54 volumio volumio[1046]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5N3hjp1WNayUPZrA8kJmJP","play_origin":"go-librespot"}}
Jul 04 19:49:54 volumio volumio[1046]: SPOTIFY: PUSH STATE SPOTIFY
Jul 04 19:49:54 volumio volumio[1046]: SPOTIFY: {"status":"pause","service":"spop","title":"Please Please Please","artist":"Sabrina Carpenter","album":"Please Please Please","albumart":"https://i.scdn.co/image/ab67616d00001e02de84adf0e48248ea2d769c3e","uri":"spotify:track:5N3hjp1WNayUPZrA8kJmJP","trackType":"spotify","seek":146000,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Jul 04 19:49:54 volumio volumio[1046]: info: CoreCommandRouter::servicePushState
Jul 04 19:49:54 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:54 volumio volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Jul 04 19:49:55 volumio volumio[1046]: info: camilladsp spawned new process with pid 2298, instance 1, run: true
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand add "https://cast.mediaonline.net.ua:443/avtoradio"
Jul 04 19:49:55 volumio volumio[1046]: info:
Jul 04 19:49:55 volumio volumio[1046]: ---------------------------- MPD announces system playlist update
Jul 04 19:49:55 volumio volumio[1046]: info: Ignoring MPD Status Update
Jul 04 19:49:55 volumio volumio[1046]: info: sendMpdCommand add "https://cast.mediaonline.net.ua:443/avtoradio" took 1 milliseconds
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand play
Jul 04 19:49:55 volumio volumio[1046]: info: ------------------------------ 3ms
Jul 04 19:49:55 volumio volumio[1046]: info: sendMpdCommand play took 1 milliseconds
Jul 04 19:49:55 volumio volumio[1046]: info:
Jul 04 19:49:55 volumio volumio[1046]: ---------------------------- MPD announces state update: player
Jul 04 19:49:55 volumio volumio[1046]: info: ControllerMpd::getState
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status
Jul 04 19:49:55 volumio volumio[1046]: info: FusionDsp - ---- read samplerate, raw:
Jul 04 19:49:55 volumio volumio[1046]: error: FusionDsp - invalid sample rate
Jul 04 19:49:55 volumio volumio[1046]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 04 19:49:55 volumio volumio[1046]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 04 19:49:55 volumio volumio[1046]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 04 19:49:55 volumio volumio[1046]: info:
Jul 04 19:49:55 volumio volumio[1046]: ---------------------------- MPD announces state update: player
Jul 04 19:49:55 volumio volumio[1046]: info: sendMpdCommand status took 28 milliseconds
Jul 04 19:49:55 volumio volumio[1046]: info: ControllerMpd::getState
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::parseState
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 04 19:49:55 volumio volumio[1046]: info:
Jul 04 19:49:55 volumio volumio[1046]: ---------------------------- MPD announces system playlist update
Jul 04 19:49:55 volumio volumio[1046]: info: Ignoring MPD Status Update
Jul 04 19:49:55 volumio volumio[1046]: info:
Jul 04 19:49:55 volumio volumio[1046]: ---------------------------- MPD announces state update: player
Jul 04 19:49:55 volumio volumio[1046]: info: ControllerMpd::getState
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status
Jul 04 19:49:55 volumio volumio[1046]: info: sendMpdCommand status took 3 milliseconds
Jul 04 19:49:55 volumio volumio[1046]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::parseState
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::parseTrackInfo
Jul 04 19:49:55 volumio volumio[1046]: info: ControllerMpd::pushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::servicePushState
Jul 04 19:49:55 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:55 volumio volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DIDO - Thank You ","artist":"Avtoradio Ukraine","album":null,"uri":"https://cast.mediaonline.net.ua:443/avtoradio","trackType":""}
Jul 04 19:49:55 volumio volumio[1046]: verbose: CURRENT POSITION 0
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus stop
Jul 04 19:49:55 volumio volumio[1046]: info: ------------------------------ 38ms
Jul 04 19:49:55 volumio volumio[1046]: error: FusionDsp - WebSocket error: [object Object]
Jul 04 19:49:55 volumio volumio[1046]: info: ------------------------------ 10ms
Jul 04 19:49:55 volumio volumio[1046]: info: sendMpdCommand status took 8 milliseconds
Jul 04 19:49:55 volumio volumio[1046]: info: sendMpdCommand playlistinfo took 7 milliseconds
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::parseState
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::parseTrackInfo
Jul 04 19:49:55 volumio volumio[1046]: info: ControllerMpd::pushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::servicePushState
Jul 04 19:49:55 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:55 volumio volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DIDO - Thank You ","artist":"Avtoradio Ukraine","album":null,"uri":"https://cast.mediaonline.net.ua:443/avtoradio","trackType":""}
Jul 04 19:49:55 volumio volumio[1046]: verbose: CURRENT POSITION 0
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play
Jul 04 19:49:55 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:49:55 volumio volumio[1046]: info: ------------------------------ 31ms
Jul 04 19:49:55 volumio volumio[1046]: info: sendMpdCommand playlistinfo took 17 milliseconds
Jul 04 19:49:55 volumio volumio[1046]: verbose: ControllerMpd::parseTrackInfo
Jul 04 19:49:55 volumio volumio[1046]: info: ControllerMpd::pushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::servicePushState
Jul 04 19:49:55 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:49:55 volumio volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DIDO - Thank You ","artist":"Avtoradio Ukraine","album":null,"uri":"https://cast.mediaonline.net.ua:443/avtoradio","trackType":""}
Jul 04 19:49:55 volumio volumio[1046]: verbose: CURRENT POSITION 0
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play
Jul 04 19:49:55 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:49:55 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:49:55 volumio volumio[1046]: info: ------------------------------ 45ms
Jul 04 19:49:55 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:49:55 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:49:55 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:49:55 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:49:56 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:49:56 volumio volumio[1046]: 46+1 records in
Jul 04 19:49:56 volumio volumio[1046]: 46+1 records out
Jul 04 19:49:56 volumio volumio[1046]: 1538176 bytes (1.5 MB, 1.5 MiB) copied, 0.00893266 s, 172 MB/s
Jul 04 19:49:56 volumio volumio[1046]: info: camilladsp spawned new process with pid 2308, instance 1, run: true
Jul 04 19:49:57 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:49:57 volumio volumio[1046]: 1+20 records in
Jul 04 19:49:57 volumio volumio[1046]: 1+20 records out
Jul 04 19:49:57 volumio volumio[1046]: 137472 bytes (137 kB, 134 KiB) copied, 0.00118212 s, 116 MB/s
Jul 04 19:49:57 volumio volumio[1046]: info: camilladsp spawned new process with pid 2313, instance 1, run: true
Jul 04 19:49:58 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:49:58 volumio volumio[1046]: 1+18 records in
Jul 04 19:49:58 volumio volumio[1046]: 1+18 records out
Jul 04 19:49:58 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.000497144 s, 266 MB/s
Jul 04 19:49:58 volumio volumio[1046]: info: camilladsp spawned new process with pid 2318, instance 1, run: true
Jul 04 19:49:59 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:49:59 volumio volumio[1046]: 1+18 records in
Jul 04 19:49:59 volumio volumio[1046]: 1+18 records out
Jul 04 19:49:59 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.000488699 s, 271 MB/s
Jul 04 19:49:59 volumio volumio[1046]: info: camilladsp spawned new process with pid 2323, instance 1, run: true
Jul 04 19:50:00 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:00 volumio volumio[1046]: 1+19 records in
Jul 04 19:50:00 volumio volumio[1046]: 1+19 records out
Jul 04 19:50:00 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.0012718 s, 104 MB/s
Jul 04 19:50:00 volumio volumio[1046]: info: camilladsp spawned new process with pid 2328, instance 1, run: true
Jul 04 19:50:01 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:01 volumio volumio[1046]: 1+18 records in
Jul 04 19:50:01 volumio volumio[1046]: 1+18 records out
Jul 04 19:50:01 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.000481977 s, 274 MB/s
Jul 04 19:50:01 volumio volumio[1046]: info: camilladsp spawned new process with pid 2333, instance 1, run: true
Jul 04 19:50:02 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:02 volumio volumio[1046]: 1+18 records in
Jul 04 19:50:02 volumio volumio[1046]: 1+18 records out
Jul 04 19:50:02 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.000493107 s, 268 MB/s
Jul 04 19:50:02 volumio volumio[1046]: info: camilladsp spawned new process with pid 2338, instance 1, run: true
Jul 04 19:50:03 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:03 volumio volumio[1046]: 1+18 records in
Jul 04 19:50:03 volumio volumio[1046]: 1+18 records out
Jul 04 19:50:03 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.000483995 s, 273 MB/s
Jul 04 19:50:03 volumio volumio[1046]: info: camilladsp spawned new process with pid 2343, instance 1, run: true
Jul 04 19:50:04 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:04 volumio volumio[1046]: 1+19 records in
Jul 04 19:50:04 volumio volumio[1046]: 1+19 records out
Jul 04 19:50:04 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.000526847 s, 251 MB/s
Jul 04 19:50:04 volumio volumio[1046]: info: camilladsp spawned new process with pid 2348, instance 1, run: true
Jul 04 19:50:05 volumio volumio[1046]: info: Preload queue cleared
Jul 04 19:50:05 volumio volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::ClearQueue
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::stop
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::stPlaybackTimer
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:05 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 04 19:50:05 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::serviceStop
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:05 volumio volumio[1046]: info: CoreCommandRouter::serviceStop
Jul 04 19:50:05 volumio volumio[1046]: info: [1720111805224] ControllerWebradio::stop
Jul 04 19:50:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::clearPlayQueue
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::saveQueue
Jul 04 19:50:05 volumio volumio[1046]: info: CoreCommandRouter::volumioPushQueue
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::addQueueItems
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::addQueueItems
Jul 04 19:50:05 volumio volumio[1046]: info: Preload queue cleared
Jul 04 19:50:05 volumio volumio[1046]: info: Adding Item to queue: http://prmstrm.1.fm:8000/smoothjazz
Jul 04 19:50:05 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Jul 04 19:50:05 volumio volumio[1046]: info: CoreCommandRouter::volumioPushQueue
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::saveQueue
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock
Jul 04 19:50:05 volumio volumio[1046]: info: CoreCommandRouter::volumioPlay
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::play index 0
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::stop
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::play index undefined
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:05 volumio volumio[1046]: info: CoreStateMachine::startPlaybackTimer
Jul 04 19:50:05 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:05 volumio volumio[1046]: info: [1720111805236] ControllerWebradio::clearAddPlayTrack
Jul 04 19:50:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop
Jul 04 19:50:05 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:50:05 volumio volumio[1046]: info:
Jul 04 19:50:05 volumio volumio[1046]: ---------------------------- MPD announces state update: player
Jul 04 19:50:05 volumio volumio[1046]: info: sendMpdCommand stop took 16 milliseconds
Jul 04 19:50:05 volumio volumio[1046]: info: ControllerMpd::getState
Jul 04 19:50:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status
Jul 04 19:50:05 volumio volumio[1046]: info: sendMpdCommand stop took 5 milliseconds
Jul 04 19:50:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear
Jul 04 19:50:05 volumio volumio[1046]: info:
Jul 04 19:50:05 volumio volumio[1046]: ---------------------------- MPD announces system playlist update
Jul 04 19:50:05 volumio volumio[1046]: info: Ignoring MPD Status Update
Jul 04 19:50:05 volumio volumio[1046]: info: sendMpdCommand status took 1 milliseconds
Jul 04 19:50:05 volumio volumio[1046]: info: sendMpdCommand clear took 1 milliseconds
Jul 04 19:50:05 volumio volumio[1046]: verbose: ControllerMpd::parseState
Jul 04 19:50:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 04 19:50:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand load "http://prmstrm.1.fm:8000/smoothjazz"
Jul 04 19:50:05 volumio volumio[1046]: error: updateQueue error: null
Jul 04 19:50:05 volumio volumio[1046]: info: ------------------------------ 2ms
Jul 04 19:50:05 volumio volumio[1046]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 04 19:50:05 volumio volumio[1046]: verbose: ControllerMpd::parseTrackInfo
Jul 04 19:50:05 volumio volumio[1046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Jul 04 19:50:05 volumio volumio[1046]: info: ------------------------------ 5ms
Jul 04 19:50:05 volumio volumio[1046]: 0+0 records in
Jul 04 19:50:05 volumio volumio[1046]: 0+0 records out
Jul 04 19:50:05 volumio volumio[1046]: 0 bytes copied, 9.6369e-05 s, 0.0 kB/s
Jul 04 19:50:05 volumio volumio[1046]: info: camilladsp spawned new process with pid 2354, instance 1, run: true
Jul 04 19:50:06 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand add "http://prmstrm.1.fm:8000/smoothjazz"
Jul 04 19:50:06 volumio volumio[1046]: info:
Jul 04 19:50:06 volumio volumio[1046]: ---------------------------- MPD announces system playlist update
Jul 04 19:50:06 volumio volumio[1046]: info: Ignoring MPD Status Update
Jul 04 19:50:06 volumio volumio[1046]: info: sendMpdCommand add "http://prmstrm.1.fm:8000/smoothjazz" took 1 milliseconds
Jul 04 19:50:06 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 04 19:50:06 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand play
Jul 04 19:50:06 volumio volumio[1046]: info: ------------------------------ 3ms
Jul 04 19:50:06 volumio volumio[1046]: info: sendMpdCommand play took 1 milliseconds
Jul 04 19:50:06 volumio volumio[1046]: 0+0 records in
Jul 04 19:50:06 volumio volumio[1046]: 0+0 records out
Jul 04 19:50:06 volumio volumio[1046]: 0 bytes copied, 9.611e-05 s, 0.0 kB/s
Jul 04 19:50:06 volumio volumio[1046]: info: camilladsp spawned new process with pid 2359, instance 1, run: true
Jul 04 19:50:07 volumio volumio[1046]: info:
Jul 04 19:50:07 volumio volumio[1046]: ---------------------------- MPD announces state update: player
Jul 04 19:50:07 volumio volumio[1046]: info: ControllerMpd::getState
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status
Jul 04 19:50:07 volumio volumio[1046]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 04 19:50:07 volumio volumio[1046]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 04 19:50:07 volumio volumio[1046]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 04 19:50:07 volumio volumio[1046]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 04 19:50:07 volumio volumio[1046]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 04 19:50:07 volumio volumio[1046]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 04 19:50:07 volumio volumio[1046]: info:
Jul 04 19:50:07 volumio volumio[1046]: ---------------------------- MPD announces state update: player
Jul 04 19:50:07 volumio volumio[1046]: info: sendMpdCommand status took 21 milliseconds
Jul 04 19:50:07 volumio volumio[1046]: info: ControllerMpd::getState
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::parseState
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 04 19:50:07 volumio volumio[1046]: info: sendMpdCommand status took 1 milliseconds
Jul 04 19:50:07 volumio volumio[1046]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::parseState
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::parseTrackInfo
Jul 04 19:50:07 volumio volumio[1046]: info: ControllerMpd::pushState
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::servicePushState
Jul 04 19:50:07 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:07 volumio volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"smoothjazz","artist":null,"album":null,"uri":"http://prmstrm.1.fm:8000/smoothjazz","trackType":""}
Jul 04 19:50:07 volumio volumio[1046]: verbose: CURRENT POSITION 0
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus stop
Jul 04 19:50:07 volumio volumio[1046]: info: ------------------------------ 24ms
Jul 04 19:50:07 volumio volumio[1046]: error: FusionDsp - WebSocket error: [object Object]
Jul 04 19:50:07 volumio volumio[1046]: error: FusionDsp - WebSocket error: [object Object]
Jul 04 19:50:07 volumio volumio[1046]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::parseTrackInfo
Jul 04 19:50:07 volumio volumio[1046]: info: ControllerMpd::pushState
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::servicePushState
Jul 04 19:50:07 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:07 volumio volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"smoothjazz","artist":null,"album":null,"uri":"http://prmstrm.1.fm:8000/smoothjazz","trackType":""}
Jul 04 19:50:07 volumio volumio[1046]: verbose: CURRENT POSITION 0
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play
Jul 04 19:50:07 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:50:07 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:50:07 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:50:07 volumio volumio[1046]: info: ------------------------------ 19ms
Jul 04 19:50:07 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:50:07 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:50:07 volumio volumio[1046]: info:
Jul 04 19:50:07 volumio volumio[1046]: ---------------------------- MPD announces system playlist update
Jul 04 19:50:07 volumio volumio[1046]: info: Ignoring MPD Status Update
Jul 04 19:50:07 volumio volumio[1046]: info:
Jul 04 19:50:07 volumio volumio[1046]: ---------------------------- MPD announces state update: player
Jul 04 19:50:07 volumio volumio[1046]: info: ControllerMpd::getState
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status
Jul 04 19:50:07 volumio volumio[1046]: info: ------------------------------ 3ms
Jul 04 19:50:07 volumio volumio[1046]: info: sendMpdCommand status took 1 milliseconds
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::parseState
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 04 19:50:07 volumio volumio[1046]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 04 19:50:07 volumio volumio[1046]: verbose: ControllerMpd::parseTrackInfo
Jul 04 19:50:07 volumio volumio[1046]: info: ControllerMpd::pushState
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::servicePushState
Jul 04 19:50:07 volumio volumio[1046]: info: CorePlayQueue::getTrack 0
Jul 04 19:50:07 volumio volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bob James - Night Sky","artist":"Smooth Jazz","album":null,"uri":"http://prmstrm.1.fm:8000/smoothjazz","trackType":""}
Jul 04 19:50:07 volumio volumio[1046]: verbose: CURRENT POSITION 0
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play
Jul 04 19:50:07 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:50:07 volumio volumio[1046]: info: CoreStateMachine::pushState
Jul 04 19:50:07 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState
Jul 04 19:50:07 volumio volumio[1046]: info: ------------------------------ 17ms
Jul 04 19:50:07 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:50:07 volumio volumio[1046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49
Jul 04 19:50:07 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:07 volumio volumio[1046]: 2+28 records in
Jul 04 19:50:07 volumio volumio[1046]: 2+28 records out
Jul 04 19:50:07 volumio volumio[1046]: 176384 bytes (176 kB, 172 KiB) copied, 0.000569568 s, 310 MB/s
Jul 04 19:50:07 volumio volumio[1046]: info: camilladsp spawned new process with pid 2365, instance 1, run: true
Jul 04 19:50:08 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:08 volumio volumio[1046]: 1+19 records in
Jul 04 19:50:08 volumio volumio[1046]: 1+19 records out
Jul 04 19:50:08 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.00128369 s, 103 MB/s
Jul 04 19:50:08 volumio volumio[1046]: info: camilladsp spawned new process with pid 2370, instance 1, run: true
Jul 04 19:50:09 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:09 volumio volumio[1046]: 1+18 records in
Jul 04 19:50:09 volumio volumio[1046]: 1+18 records out
Jul 04 19:50:09 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.00122784 s, 108 MB/s
Jul 04 19:50:09 volumio volumio[1046]: info: camilladsp spawned new process with pid 2375, instance 1, run: true
Jul 04 19:50:10 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:50:10 volumio volumio[1046]: info: FusionDsp - IP adress is ---------------------------192.168.0.106
Jul 04 19:50:11 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:11 volumio volumio[1046]: 1+18 records in
Jul 04 19:50:11 volumio volumio[1046]: 1+18 records out
Jul 04 19:50:11 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.00123827 s, 107 MB/s
Jul 04 19:50:11 volumio volumio[1046]: info: camilladsp spawned new process with pid 2381, instance 1, run: true
Jul 04 19:50:12 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:12 volumio volumio[1046]: 1+18 records in
Jul 04 19:50:12 volumio volumio[1046]: 1+18 records out
Jul 04 19:50:12 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.00124706 s, 106 MB/s
Jul 04 19:50:12 volumio volumio[1046]: info: camilladsp spawned new process with pid 2386, instance 1, run: true
Jul 04 19:50:13 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:13 volumio volumio[1046]: 1+19 records in
Jul 04 19:50:13 volumio volumio[1046]: 1+19 records out
Jul 04 19:50:13 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.00130047 s, 102 MB/s
Jul 04 19:50:13 volumio volumio[1046]: info: camilladsp spawned new process with pid 2391, instance 1, run: true
Jul 04 19:50:14 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:14 volumio volumio[1046]: 1+18 records in
Jul 04 19:50:14 volumio volumio[1046]: 1+18 records out
Jul 04 19:50:14 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.00124401 s, 106 MB/s
Jul 04 19:50:14 volumio volumio[1046]: info: camilladsp spawned new process with pid 2397, instance 1, run: true
Jul 04 19:50:15 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:15 volumio volumio[1046]: 1+18 records in
Jul 04 19:50:15 volumio volumio[1046]: 1+18 records out
Jul 04 19:50:15 volumio volumio[1046]: 132288 bytes (132 kB, 129 KiB) copied, 0.00124538 s, 106 MB/s
Jul 04 19:50:15 volumio volumio[1046]: info: camilladsp spawned new process with pid 2402, instance 1, run: true
Jul 04 19:50:16 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:16 volumio volumio[1046]: 11+1 records in
Jul 04 19:50:16 volumio volumio[1046]: 11+1 records out
Jul 04 19:50:16 volumio volumio[1046]: 390720 bytes (391 kB, 382 KiB) copied, 0.00212194 s, 184 MB/s
Jul 04 19:50:16 volumio volumio[1046]: info: camilladsp spawned new process with pid 2407, instance 1, run: true
Jul 04 19:50:17 volumio volumio[1046]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object]
Jul 04 19:50:17 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp
Jul 04 19:50:17 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:17 volumio volumio[1046]: 2+30 records in
Jul 04 19:50:17 volumio volumio[1046]: 2+30 records out
Jul 04 19:50:17 volumio volumio[1046]: 182528 bytes (183 kB, 178 KiB) copied, 0.00130282 s, 140 MB/s
Jul 04 19:50:17 volumio volumio[1046]: info: camilladsp spawned new process with pid 2412, instance 1, run: true
Jul 04 19:50:17 volumio volumio[1046]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 04 19:50:17 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:50:17 volumio volumio[1046]: info: CoreCommandRouter::Close All Modals sent
Jul 04 19:50:17 volumio volumio[1046]: error: FusionDsp - WebSocket error: [object Object]
Jul 04 19:50:18 volumio volumio[1046]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable
Jul 04 19:50:18 volumio volumio[1046]: 9+1 records in
Jul 04 19:50:18 volumio volumio[1046]: 9+1 records out
Jul 04 19:50:18 volumio volumio[1046]: 322096 bytes (322 kB, 315 KiB) copied, 0.00175037 s, 184 MB/s
Jul 04 19:50:18 volumio volumio[1046]: info: camilladsp spawned new process with pid 2417, instance 1, run: true
Jul 04 19:50:20 volumio volumio[1046]: info: CALLMETHOD: audio_interface fusiondsp disableeffect
Jul 04 19:50:20 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect
Jul 04 19:50:20 volumio volumio[1046]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 04 19:50:20 volumio volumio[1046]: info: FusionDsp - Effects disabled
Jul 04 19:50:20 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:50:20 volumio volumio[1046]: info: CoreCommandRouter::Close All Modals sent
Jul 04 19:50:20 volumio volumio[1046]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 04 19:50:22 volumio volumio[1046]: info: CALLMETHOD: audio_interface fusiondsp enableeffect
Jul 04 19:50:22 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect
Jul 04 19:50:22 volumio volumio[1046]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 04 19:50:22 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:50:22 volumio volumio[1046]: info: CoreCommandRouter::Close All Modals sent
Jul 04 19:50:22 volumio volumio[1046]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 04 19:50:24 volumio volumio[1046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object]
Jul 04 19:50:24 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq
Jul 04 19:50:25 volumio volumio[1046]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 04 19:50:25 volumio volumio[1046]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 04 19:50:25 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:50:25 volumio volumio[1046]: info: CoreCommandRouter::Close All Modals sent
Jul 04 19:50:31 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:50:34 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:50:34 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 04 19:50:35 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 04 19:50:35 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 04 19:50:35 volumio volumio[1046]: info: Received Get System Version
Jul 04 19:50:35 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 04 19:50:44 volumio volumio[1046]: info: Getting Alsa Cards List without I2S DAC
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Jul 04 19:50:44 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 04 19:50:49 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState
Jul 04 19:50:56 volumio volumio[1046]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________
Jul 04 19:50:56 volumio volumio[1046]: info: PLUGIN onReboot : networkfs
Jul 04 19:50:56 volumio volumio[1046]: info: PLUGIN onReboot : audiophonicsonoff
Jul 04 19:50:56 volumio volumio[1046]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 04 19:50:56 volumio volumio[1046]: TypeError: Cannot read property 'writeSync' of undefined
Jul 04 19:50:56 volumio volumio[1046]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25)
Jul 04 19:50:56 volumio volumio[1046]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30)
Jul 04 19:50:56 volumio volumio[1046]: at HashMap. (/volumio/app/pluginmanager.js:668:31)
Jul 04 19:50:56 volumio volumio[1046]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10)
Jul 04 19:50:56 volumio volumio[1046]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7)
Jul 04 19:50:56 volumio volumio[1046]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20)
Jul 04 19:50:56 volumio volumio[1046]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22)
Jul 04 19:50:56 volumio volumio[1046]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:857:33)
Jul 04 19:50:56 volumio volumio[1046]: at Socket.emit (events.js:315:20)
Jul 04 19:50:56 volumio volumio[1046]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Jul 04 19:50:56 volumio volumio[1046]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Jul 04 19:50:56 volumio volumio[1046]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 04 19:50:56 volumio sudo[2508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-04 19:49
Jul 04 19:50:56 volumio sudo[2508]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"