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