-- Logs begin at Tue 2024-10-01 22:44:27 CEST, end at Tue 2024-10-01 22:57:00 CEST. -- Oct 01 22:56:05 volumio sudo[9492]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:07 volumio volumio[9159]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:07 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:07 volumio volumio[9159]: info: Prefetching next song Oct 01 22:56:07 volumio volumio[9159]: info: [jellyfin-play] Stream URL for Chaos Space Marine: http://192.168.2.158:8096/Audio/59405472928aca681fa4d24ad5714be6/stream.flac?static=true&mediaSourceId=59405472928aca681fa4d24ad5714be6&tag=2353267c712852ce93948aeeea10c855 Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.2.158:8096/Audio/59405472928aca681fa4d24ad5714be6/stream.flac?static=true&mediaSourceId=59405472928aca681fa4d24ad5714be6&tag=2353267c712852ce93948aeeea10c855&t.flac" Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:07 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:07 volumio volumio[9159]: info: sendMpdCommand addid "http://192.168.2.158:8096/Audio/59405472928aca681fa4d24ad5714be6/stream.flac?static=true&mediaSourceId=59405472928aca681fa4d24ad5714be6&tag=2353267c712852ce93948aeeea10c855&t.flac" took 1 milliseconds Oct 01 22:56:07 volumio volumio[9159]: verbose: MPD COMMAND [object Object] Oct 01 22:56:07 volumio volumio[9159]: verbose: MPD COMMAND [object Object] Oct 01 22:56:07 volumio volumio[9159]: verbose: MPD COMMAND [object Object] Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:07 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:07 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:07 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 4ms Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 2ms Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 2ms Oct 01 22:56:07 volumio volumio[9159]: info: [jellyfin-play] Prefetched and added song to MPD queue: Chaos Space Marine Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:07 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:07 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces state update: options Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 4ms Oct 01 22:56:07 volumio volumio[9159]: info: sendMpdCommand consume 1 took 3 milliseconds Oct 01 22:56:07 volumio volumio[9159]: info: ControllerMpd::getState Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand status Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 2ms Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 3ms Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces state update: options Oct 01 22:56:07 volumio volumio[9159]: info: ControllerMpd::getState Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand status Oct 01 22:56:07 volumio volumio[9159]: info: Oct 01 22:56:07 volumio volumio[9159]: ---------------------------- MPD announces state update: options Oct 01 22:56:07 volumio volumio[9159]: info: ControllerMpd::getState Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand status Oct 01 22:56:07 volumio volumio[9159]: info: sendMpdCommand status took 6 milliseconds Oct 01 22:56:07 volumio volumio[9159]: info: sendMpdCommand status took 5 milliseconds Oct 01 22:56:07 volumio volumio[9159]: info: sendMpdCommand status took 4 milliseconds Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::parseState Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::parseState Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::parseState Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 22:56:07 volumio volumio[9159]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 01 22:56:07 volumio volumio[9159]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 01 22:56:07 volumio volumio[9159]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::parseTrackInfo Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::parseTrackInfo Oct 01 22:56:07 volumio volumio[9159]: verbose: ControllerMpd::parseTrackInfo Oct 01 22:56:07 volumio volumio[9159]: info: ControllerMpd::pushState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::servicePushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::syncState Oct 01 22:56:07 volumio volumio[9159]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:07 volumio volumio[9159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49743,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3096 Kbps","isStreaming":false,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","uri":"http://192.168.2.158:8096/Audio/32a53ed051b38217e6f09feb0af420e7/stream.flac?static=true&mediaSourceId=32a53ed051b38217e6f09feb0af420e7&tag=16e4f006d5fb62e4bafe46f52ab955a1&t.flac","trackType":"flac"} Oct 01 22:56:07 volumio volumio[9159]: verbose: CURRENT POSITION 0 Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::syncState stateService play Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::syncState currentStatus play Oct 01 22:56:07 volumio volumio[9159]: info: Received an update from plugin. extracting info from payload Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:07 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:07 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: ControllerMpd::pushState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::servicePushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::syncState Oct 01 22:56:07 volumio volumio[9159]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:07 volumio volumio[9159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49743,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3096 Kbps","isStreaming":false,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","uri":"http://192.168.2.158:8096/Audio/32a53ed051b38217e6f09feb0af420e7/stream.flac?static=true&mediaSourceId=32a53ed051b38217e6f09feb0af420e7&tag=16e4f006d5fb62e4bafe46f52ab955a1&t.flac","trackType":"flac"} Oct 01 22:56:07 volumio volumio[9159]: verbose: CURRENT POSITION 0 Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::syncState stateService play Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::syncState currentStatus play Oct 01 22:56:07 volumio volumio[9159]: info: Received an update from plugin. extracting info from payload Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:07 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:07 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: ControllerMpd::pushState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::servicePushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::syncState Oct 01 22:56:07 volumio volumio[9159]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:07 volumio volumio[9159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49743,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3096 Kbps","isStreaming":false,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","uri":"http://192.168.2.158:8096/Audio/32a53ed051b38217e6f09feb0af420e7/stream.flac?static=true&mediaSourceId=32a53ed051b38217e6f09feb0af420e7&tag=16e4f006d5fb62e4bafe46f52ab955a1&t.flac","trackType":"flac"} Oct 01 22:56:07 volumio volumio[9159]: verbose: CURRENT POSITION 0 Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::syncState stateService play Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::syncState currentStatus play Oct 01 22:56:07 volumio volumio[9159]: info: Received an update from plugin. extracting info from payload Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:07 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:07 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:07 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 58ms Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 57ms Oct 01 22:56:07 volumio volumio[9159]: info: ------------------------------ 57ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":0,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/32a53ed051b38217e6f09feb0af420e7/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=32a53ed051b38217e6f09feb0af420e7","trackType":"flac","seek":49234,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"bitrate":"3096 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":"","dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 49234 and duration: 55000 ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 89.51636363636364% Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.02, New progression: 89.51636363636364 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":0,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/32a53ed051b38217e6f09feb0af420e7/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=32a53ed051b38217e6f09feb0af420e7","trackType":"flac","seek":49234,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"bitrate":"3096 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":"","dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 49234 and duration: 55000 ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 89.51636363636364% Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 89.51636363636364, New progression: 89.51636363636364 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":0,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/32a53ed051b38217e6f09feb0af420e7/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=32a53ed051b38217e6f09feb0af420e7","trackType":"flac","seek":49234,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"bitrate":"3096 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":"","dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 49234 and duration: 55000 ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 89.51636363636364% Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 89.51636363636364, New progression: 89.51636363636364 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":0,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/32a53ed051b38217e6f09feb0af420e7/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=32a53ed051b38217e6f09feb0af420e7","trackType":"flac","seek":49234,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"bitrate":"3096 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":"","dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 49234 and duration: 55000 ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 89.51636363636364% Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 89.51636363636364, New progression: 89.51636363636364 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":0,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/32a53ed051b38217e6f09feb0af420e7/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=32a53ed051b38217e6f09feb0af420e7","trackType":"flac","seek":49234,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"bitrate":"3096 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":"","dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 49234 and duration: 55000 ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 89.51636363636364% Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 89.51636363636364, New progression: 89.51636363636364 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":0,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/32a53ed051b38217e6f09feb0af420e7/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=32a53ed051b38217e6f09feb0af420e7","trackType":"flac","seek":49234,"duration":55,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"bitrate":"3096 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":"","dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:07 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 49234 and duration: 55000 ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 89.51636363636364% Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 89.51636363636364, New progression: 89.51636363636364 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:07 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:07 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:07 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 12) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:07 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:07 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 13) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:07 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:07 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 14) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:07 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:07 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 15) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:07 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:07 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 16) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:07 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:07 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:07 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:07 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:07 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:07 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:07 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:07 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 17) Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with elapsedTime: 10 ms and duration: 55000 ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 89.51636363636364, New progression: 0.01818181818181818 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 0 Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Realtime Updating fader 0 progression to 0.01818181818181818% Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with elapsedTime: 10 ms and duration: 55000 ms Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 1 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.02, New progression: 0.01818181818181818 Oct 01 22:56:07 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 1 Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Realtime Updating fader 1 progression to 0.01818181818181818% Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Combined Fader Move: {"idx":[0,1],"target":[0.01818181818181818,0.01818181818181818],"speed":[100,100]} Oct 01 22:56:07 volumio volumio[9159]: debug: Moving Faders: 0,1 to 0.01818181818181818,0.01818181818181818 with speed: 100,100 Interrupting: true Oct 01 22:56:07 volumio volumio[9159]: debug: ProgressionsDict sent analysis (faderIdx):(AmountPositions): 0: 1, 1: 1 Oct 01 22:56:07 volumio volumio[9159]: debug: FADER MOVE PROTOCOL: Oct 01 22:56:07 volumio volumio[9159]: Moved Faders: [0,1] Oct 01 22:56:07 volumio volumio[9159]: StartPoints: [0.01818181818181818,0.01818181818181818,0,0] Oct 01 22:56:07 volumio volumio[9159]: Targets: [0.01818181818181818,0.01818181818181818] Oct 01 22:56:07 volumio volumio[9159]: RampStartActual: [0.01818181818181818,0.01818181818181818] Oct 01 22:56:07 volumio volumio[9159]: RampEndActual: [0.01818181818181818,0.01818181818181818] Oct 01 22:56:07 volumio volumio[9159]: Speeds: [100,100] Oct 01 22:56:07 volumio volumio[9159]: StepSize: [0.0018181818181818195,0.0018181818181818195] Oct 01 22:56:07 volumio volumio[9159]: Steps: [1,1] Oct 01 22:56:07 volumio volumio[9159]: Duration: 1ms Oct 01 22:56:07 volumio volumio[9159]: FaderInfo: Oct 01 22:56:07 volumio volumio[9159]: Fader 0: FADER INFO: index: 0 position: 3 progression: 0.01818181818181818 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:07 volumio volumio[9159]: Fader 1: FADER INFO: index: 1 position: 3 progression: 0.01818181818181818 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Stopping continuous seek update due to playback stop Oct 01 22:56:07 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:12 volumio volumio[9159]: info: CoreStateMachine::startPlaybackTimer Oct 01 22:56:12 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:12 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:12 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:12 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:12 volumio volumio[9159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 22:56:12 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:12 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:12 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:12 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:12 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:12 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":1,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/59405472928aca681fa4d24ad5714be6/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=59405472928aca681fa4d24ad5714be6","trackType":"flac","seek":250,"duration":216,"samplerate":"96 kHz","bitdepth":"24 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:12 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:12 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:12 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 250 and duration: 216000 ms Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 0.11574074074074073% Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.01818181818181818, New progression: 0.11574074074074073 Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 0 Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:12 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:12 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:12 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:12 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:12 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:12 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:12 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:12 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:12 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:12 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:12 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:12 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:12 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:12 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:12 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:12 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:12 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:12 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:12 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 18) Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with elapsedTime: 11 ms and duration: 216000 ms Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.11574074074074073, New progression: 0.005092592592592592 Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 0 Oct 01 22:56:12 volumio volumio[9159]: debug: motorized_fader_control: Realtime Updating fader 0 progression to 0.005092592592592592% Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with elapsedTime: 11 ms and duration: 216000 ms Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 1 Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.01818181818181818, New progression: 0.005092592592592592 Oct 01 22:56:12 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 1 Oct 01 22:56:12 volumio volumio[9159]: debug: motorized_fader_control: Realtime Updating fader 1 progression to 0.005092592592592592% Oct 01 22:56:12 volumio volumio[9159]: debug: motorized_fader_control: Combined Fader Move: {"idx":[0,1],"target":[0.005092592592592592,0.005092592592592592],"speed":[100,100]} Oct 01 22:56:12 volumio volumio[9159]: debug: Moving Faders: 0,1 to 0.005092592592592592,0.005092592592592592 with speed: 100,100 Interrupting: true Oct 01 22:56:12 volumio volumio[9159]: debug: ProgressionsDict sent analysis (faderIdx):(AmountPositions): 0: 1, 1: 1 Oct 01 22:56:12 volumio volumio[9159]: debug: FADER MOVE PROTOCOL: Oct 01 22:56:12 volumio volumio[9159]: Moved Faders: [0,1] Oct 01 22:56:12 volumio volumio[9159]: StartPoints: [0.005092592592592592,0.005092592592592592,0,0] Oct 01 22:56:12 volumio volumio[9159]: Targets: [0.005092592592592592,0.005092592592592592] Oct 01 22:56:12 volumio volumio[9159]: RampStartActual: [0.005092592592592592,0.005092592592592592] Oct 01 22:56:12 volumio volumio[9159]: RampEndActual: [0.005092592592592592,0.005092592592592592] Oct 01 22:56:12 volumio volumio[9159]: Speeds: [100,100] Oct 01 22:56:12 volumio volumio[9159]: StepSize: [0.013089225589225588,0.013089225589225588] Oct 01 22:56:12 volumio volumio[9159]: Steps: [1,1] Oct 01 22:56:12 volumio volumio[9159]: Duration: 1ms Oct 01 22:56:12 volumio volumio[9159]: FaderInfo: Oct 01 22:56:12 volumio volumio[9159]: Fader 0: FADER INFO: index: 0 position: 1 progression: 0.005092592592592592 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:12 volumio volumio[9159]: Fader 1: FADER INFO: index: 1 position: 1 progression: 0.005092592592592592 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:12 volumio volumio[9159]: debug: motorized_fader_control: Stopping continuous seek update due to playback stop Oct 01 22:56:12 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:13 volumio volumio[9159]: info: Oct 01 22:56:13 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:13 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:13 volumio volumio[9159]: info: ControllerMpd::getState Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand status Oct 01 22:56:13 volumio volumio[9159]: info: Oct 01 22:56:13 volumio volumio[9159]: ---------------------------- MPD announces state update: player Oct 01 22:56:13 volumio volumio[9159]: info: ControllerMpd::getState Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand status Oct 01 22:56:13 volumio volumio[9159]: info: Oct 01 22:56:13 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:13 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:13 volumio volumio[9159]: info: Oct 01 22:56:13 volumio volumio[9159]: ---------------------------- MPD announces state update: player Oct 01 22:56:13 volumio volumio[9159]: info: ControllerMpd::getState Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand status Oct 01 22:56:13 volumio volumio[9159]: info: Oct 01 22:56:13 volumio volumio[9159]: ---------------------------- MPD announces system playlist update Oct 01 22:56:13 volumio volumio[9159]: info: Ignoring MPD Status Update Oct 01 22:56:13 volumio volumio[9159]: info: Oct 01 22:56:13 volumio volumio[9159]: ---------------------------- MPD announces state update: player Oct 01 22:56:13 volumio volumio[9159]: info: ControllerMpd::getState Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand status Oct 01 22:56:13 volumio volumio[9159]: info: sendMpdCommand status took 9 milliseconds Oct 01 22:56:13 volumio volumio[9159]: info: ------------------------------ 11ms Oct 01 22:56:13 volumio volumio[9159]: info: sendMpdCommand status took 8 milliseconds Oct 01 22:56:13 volumio volumio[9159]: info: ------------------------------ 7ms Oct 01 22:56:13 volumio volumio[9159]: info: sendMpdCommand status took 6 milliseconds Oct 01 22:56:13 volumio volumio[9159]: info: ------------------------------ 5ms Oct 01 22:56:13 volumio volumio[9159]: info: sendMpdCommand status took 3 milliseconds Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::parseState Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::parseState Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::parseState Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::parseState Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 22:56:13 volumio volumio[9159]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 01 22:56:13 volumio volumio[9159]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 01 22:56:13 volumio volumio[9159]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::parseTrackInfo Oct 01 22:56:13 volumio volumio[9159]: verbose: ControllerMpd::parseTrackInfo Oct 01 22:56:13 volumio volumio[9159]: info: ControllerMpd::pushState Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::servicePushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::syncState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2064 Kbps","isStreaming":false,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","uri":"http://192.168.2.158:8096/Audio/59405472928aca681fa4d24ad5714be6/stream.flac?static=true&mediaSourceId=59405472928aca681fa4d24ad5714be6&tag=2353267c712852ce93948aeeea10c855&t.flac","trackType":"flac"} Oct 01 22:56:13 volumio volumio[9159]: verbose: CURRENT POSITION 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::syncState stateService play Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::syncState currentStatus play Oct 01 22:56:13 volumio volumio[9159]: info: Received an update from plugin. extracting info from payload Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:13 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:13 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:13 volumio volumio[9159]: info: ControllerMpd::pushState Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::servicePushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::syncState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2064 Kbps","isStreaming":false,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","uri":"http://192.168.2.158:8096/Audio/59405472928aca681fa4d24ad5714be6/stream.flac?static=true&mediaSourceId=59405472928aca681fa4d24ad5714be6&tag=2353267c712852ce93948aeeea10c855&t.flac","trackType":"flac"} Oct 01 22:56:13 volumio volumio[9159]: verbose: CURRENT POSITION 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::syncState stateService play Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::syncState currentStatus play Oct 01 22:56:13 volumio volumio[9159]: info: Received an update from plugin. extracting info from payload Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:13 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:13 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:13 volumio volumio[9159]: info: ControllerMpd::pushState Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::servicePushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::syncState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2064 Kbps","isStreaming":false,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","uri":"http://192.168.2.158:8096/Audio/59405472928aca681fa4d24ad5714be6/stream.flac?static=true&mediaSourceId=59405472928aca681fa4d24ad5714be6&tag=2353267c712852ce93948aeeea10c855&t.flac","trackType":"flac"} Oct 01 22:56:13 volumio volumio[9159]: verbose: CURRENT POSITION 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::syncState stateService play Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::syncState currentStatus play Oct 01 22:56:13 volumio volumio[9159]: info: Received an update from plugin. extracting info from payload Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:13 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:13 volumio volumio[9159]: info: CoreStateMachine::pushState Oct 01 22:56:13 volumio volumio[9159]: debug: CoreStateMachine::getState Oct 01 22:56:13 volumio volumio[9159]: info: CorePlayQueue::getTrack 1 Oct 01 22:56:13 volumio volumio[9159]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:13 volumio volumio[9159]: debug: API:pushState Oct 01 22:56:13 volumio volumio[9159]: info: ------------------------------ 100ms Oct 01 22:56:13 volumio volumio[9159]: info: ------------------------------ 99ms Oct 01 22:56:13 volumio volumio[9159]: info: ------------------------------ 98ms Oct 01 22:56:13 volumio volumio[9159]: info: Pushing Favourites {"favourite":false} Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":1,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/59405472928aca681fa4d24ad5714be6/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=59405472928aca681fa4d24ad5714be6","trackType":"flac","seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 84 and duration: 217000 ms Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 0.03870967741935484% Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.005092592592592592, New progression: 0.03870967741935484 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":1,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/59405472928aca681fa4d24ad5714be6/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=59405472928aca681fa4d24ad5714be6","trackType":"flac","seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 84 and duration: 217000 ms Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 0.03870967741935484% Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.03870967741935484, New progression: 0.03870967741935484 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":1,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/59405472928aca681fa4d24ad5714be6/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=59405472928aca681fa4d24ad5714be6","trackType":"flac","seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 84 and duration: 217000 ms Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 0.03870967741935484% Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.03870967741935484, New progression: 0.03870967741935484 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":1,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/59405472928aca681fa4d24ad5714be6/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=59405472928aca681fa4d24ad5714be6","trackType":"flac","seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 84 and duration: 217000 ms Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 0.03870967741935484% Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.03870967741935484, New progression: 0.03870967741935484 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":1,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/59405472928aca681fa4d24ad5714be6/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=59405472928aca681fa4d24ad5714be6","trackType":"flac","seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 84 and duration: 217000 ms Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 0.03870967741935484% Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.03870967741935484, New progression: 0.03870967741935484 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Received pushState update Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: State cached: {"status":"play","position":1,"title":"Chaos Space Marine","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/59405472928aca681fa4d24ad5714be6/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=59405472928aca681fa4d24ad5714be6","trackType":"flac","seek":84,"duration":217,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 0 with output type seek Oct 01 22:56:13 volumio volumio[9159]: debug: #### motorized_fader_control: Getting seek progression for track Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getTrackProgression triggered Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with seek: 84 and duration: 217000 ms Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Track progression calculated: 0.03870967741935484% Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.03870967741935484, New progression: 0.03870967741935484 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Output seek has not changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Processing fader 1 with output type volume Oct 01 22:56:13 volumio volumio[9159]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:13 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:13 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 19) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:13 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:13 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 20) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:13 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:13 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 21) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:13 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:13 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 22) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:13 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:13 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 23) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: TypeError: state.get is not a function Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:826:26) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleFaderBehavior (/data/plugins/user_interface/motorized_fader_control/index.js:685:32) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:577:10) Oct 01 22:56:13 volumio volumio[9159]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:56:13 volumio volumio[9159]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:56:13 volumio volumio[9159]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:56:13 volumio volumio[9159]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:56:13 volumio volumio[9159]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:56:13 volumio volumio[9159]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:56:13 volumio volumio[9159]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:56:13 volumio volumio[9159]: (node:9159) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 24) Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with elapsedTime: 13 ms and duration: 217000 ms Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.03870967741935484, New progression: 0.005990783410138248 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 0 Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Realtime Updating fader 0 progression to 0.005990783410138248% Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Calculating progression with elapsedTime: 13 ms and duration: 217000 ms Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Checking if output seek has changed for fader 1 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Previous progression: 0.005092592592592592, New progression: 0.005990783410138248 Oct 01 22:56:13 volumio volumio[9159]: info: motorized_fader_control: Output seek has changed for fader 1 Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Realtime Updating fader 1 progression to 0.005990783410138248% Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Combined Fader Move: {"idx":[0,1],"target":[0.005990783410138248,0.005990783410138248],"speed":[100,100]} Oct 01 22:56:13 volumio volumio[9159]: debug: Moving Faders: 0,1 to 0.005990783410138248,0.005990783410138248 with speed: 100,100 Interrupting: true Oct 01 22:56:13 volumio volumio[9159]: debug: ProgressionsDict sent analysis (faderIdx):(AmountPositions): 0: 1, 1: 1 Oct 01 22:56:13 volumio volumio[9159]: debug: FADER MOVE PROTOCOL: Oct 01 22:56:13 volumio volumio[9159]: Moved Faders: [0,1] Oct 01 22:56:13 volumio volumio[9159]: StartPoints: [0.005990783410138248,0.005990783410138248,0,0] Oct 01 22:56:13 volumio volumio[9159]: Targets: [0.005990783410138248,0.005990783410138248] Oct 01 22:56:13 volumio volumio[9159]: RampStartActual: [0.005990783410138248,0.005990783410138248] Oct 01 22:56:13 volumio volumio[9159]: RampEndActual: [0.005990783410138248,0.005990783410138248] Oct 01 22:56:13 volumio volumio[9159]: Speeds: [100,100] Oct 01 22:56:13 volumio volumio[9159]: StepSize: [0.0008981908175456563,0.0008981908175456563] Oct 01 22:56:13 volumio volumio[9159]: Steps: [1,1] Oct 01 22:56:13 volumio volumio[9159]: Duration: 3ms Oct 01 22:56:13 volumio volumio[9159]: FaderInfo: Oct 01 22:56:13 volumio volumio[9159]: Fader 0: FADER INFO: index: 0 position: 1 progression: 0.005990783410138248 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:13 volumio volumio[9159]: Fader 1: FADER INFO: index: 1 position: 1 progression: 0.005990783410138248 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Stopping continuous seek update due to playback stop Oct 01 22:56:13 volumio volumio[9159]: debug: motorized_fader_control: Stopping ContinuousSeekUpdate Oct 01 22:56:13 volumio volumio[9159]: info: [jellyfin-play]: Reported 'stop' for song: Intro (at 54986 ms) Oct 01 22:56:13 volumio volumio[9159]: info: [jellyfin-play]: Reported 'start' for song: Chaos Space Marine (at 84 ms) Oct 01 22:56:43 volumio sudo[9722]: volumio : TTY=pts/3 ; PWD=/home/volumio/volumio-plugins-sources/motorized_fader_control ; USER=root ; COMMAND=/bin/systemctl stop volumio.service Oct 01 22:56:43 volumio sudo[9722]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:43 volumio systemd[1]: Stopping Volumio Backend Module... Oct 01 22:56:43 volumio go-librespot[9334]: time="2024-10-01T22:56:43+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 01 22:56:43 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Oct 01 22:56:43 volumio systemd[1]: volumio.service: Succeeded. Oct 01 22:56:43 volumio volumio-remote-updater[608]: [2024-10-01 22:56:43] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 01 22:56:43 volumio systemd[1]: Stopped Volumio Backend Module. Oct 01 22:56:43 volumio sudo[9722]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:43 volumio volumio-remote-updater[608]: [2024-10-01 22:56:43] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 01 22:56:43 volumio sudo[9725]: volumio : TTY=pts/3 ; PWD=/home/volumio/volumio-plugins-sources/motorized_fader_control ; USER=root ; COMMAND=/bin/systemctl start volumio.service Oct 01 22:56:43 volumio sudo[9725]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:44 volumio systemd[1]: Started dynamicswap service. Oct 01 22:56:44 volumio systemd[1]: Started Volumio Backend Module. Oct 01 22:56:44 volumio sudo[9725]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:44 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 01 22:56:45 volumio volumio[9733]: info: ------------------------------------------- Oct 01 22:56:45 volumio volumio[9733]: info: ----- Volumio3 ---- Oct 01 22:56:45 volumio volumio[9733]: info: ------------------------------------------- Oct 01 22:56:45 volumio volumio[9733]: info: ----- System startup ---- Oct 01 22:56:45 volumio volumio[9733]: info: ------------------------------------------- Oct 01 22:56:46 volumio volumio[9733]: info: MYVOLUMIO Environment detected Oct 01 22:56:46 volumio volumio[9733]: info: Plugin folders cleanup Oct 01 22:56:46 volumio volumio[9733]: info: Scanning into folder /volumio/app/plugins/ Oct 01 22:56:46 volumio volumio[9733]: info: Scanning category audio_interface Oct 01 22:56:46 volumio volumio[9733]: info: Scanning category miscellanea Oct 01 22:56:46 volumio volumio[9733]: info: Scanning category music_service Oct 01 22:56:46 volumio volumio[9733]: info: Scanning category plugins.json Oct 01 22:56:46 volumio volumio[9733]: info: Scanning category system_controller Oct 01 22:56:46 volumio volumio[9733]: info: Scanning category user_interface Oct 01 22:56:46 volumio volumio[9733]: info: Scanning into folder /data/plugins/ Oct 01 22:56:46 volumio volumio[9733]: info: Scanning category music_service Oct 01 22:56:46 volumio volumio[9733]: info: Scanning category user_interface Oct 01 22:56:46 volumio volumio[9733]: info: Plugin folders cleanup completed Oct 01 22:56:46 volumio volumio[9733]: info: ------------------------------------------- Oct 01 22:56:46 volumio volumio[9733]: info: ----- Core plugins startup ---- Oct 01 22:56:46 volumio volumio[9733]: info: ------------------------------------------- Oct 01 22:56:46 volumio volumio[9733]: info: Loading plugins from folder /volumio/app/plugins/ Oct 01 22:56:46 volumio volumio[9733]: info: Adding plugin upnp to MyMusic Plugins Oct 01 22:56:46 volumio volumio[9733]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 01 22:56:46 volumio volumio[9733]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 01 22:56:46 volumio volumio[9733]: info: Loading plugins from folder /data/plugins/ Oct 01 22:56:46 volumio volumio[9733]: info: Loading plugin "system"... Oct 01 22:56:46 volumio volumio[9733]: info: Loading plugin "appearance"... Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "network"... Oct 01 22:56:47 volumio volumio[9733]: info: Refreshing Cached IP Addresses Oct 01 22:56:47 volumio sudo[9776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 22:56:47 volumio sudo[9776]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:47 volumio sudo[9778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 22:56:47 volumio sudo[9778]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "services"... Oct 01 22:56:47 volumio sudo[9778]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:47 volumio sudo[9776]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "alsa_controller"... Oct 01 22:56:47 volumio sudo[9781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 01 22:56:47 volumio sudo[9781]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:47 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "wizard"... Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "networkfs"... Oct 01 22:56:47 volumio volumio[9733]: info: Starting Udev Watcher for removable devices Oct 01 22:56:47 volumio volumio[9733]: info: Ignoring mount for partition: boot Oct 01 22:56:47 volumio volumio[9733]: info: Ignoring mount for partition: volumio Oct 01 22:56:47 volumio volumio[9733]: info: Ignoring mount for partition: volumio_data Oct 01 22:56:47 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "volumio_command_line_client"... Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "upnp"... Oct 01 22:56:47 volumio volumio[9733]: info: [1727816207841] Starting Upmpd Daemon Oct 01 22:56:47 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "my_music"... Oct 01 22:56:47 volumio volumio[9733]: info: Loading plugin "mpd"... Oct 01 22:56:48 volumio volumio[9733]: info: Loading plugin "upnp_browser"... Oct 01 22:56:48 volumio volumio[9733]: info: Loading plugin "alarm-clock"... Oct 01 22:56:48 volumio volumio[9733]: info: Loading plugin "airplay_emulation"... Oct 01 22:56:48 volumio volumio[9733]: info: Starting Shairport Sync Oct 01 22:56:48 volumio volumio[9733]: info: Loading plugin "last_100"... Oct 01 22:56:48 volumio volumio[9733]: info: Loading plugin "webradio"... Oct 01 22:56:48 volumio volumio[9733]: info: Loading plugin "i2s_dacs"... Oct 01 22:56:48 volumio volumio[9733]: info: Loading plugin "volumiodiscovery"... Oct 01 22:56:48 volumio volumio[9733]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 01 22:56:48 volumio volumio[9733]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 01 22:56:48 volumio volumio[9733]: *** WARNING *** For more information see Oct 01 22:56:48 volumio node[9733]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 01 22:56:48 volumio volumio[9733]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 01 22:56:48 volumio volumio[9733]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 01 22:56:48 volumio volumio[9733]: *** WARNING *** For more information see Oct 01 22:56:48 volumio node[9733]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 01 22:56:48 volumio node[9733]: *** WARNING *** For more information see Oct 01 22:56:48 volumio node[9733]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 01 22:56:48 volumio node[9733]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 01 22:56:48 volumio node[9733]: *** WARNING *** For more information see Oct 01 22:56:48 volumio volumio[9733]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 01 22:56:48 volumio volumio[9733]: info: Discovery: Started advertising with name: Volumio Oct 01 22:56:48 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 22:56:48 volumio volumio[9733]: info: Loading plugin "jellyfin"... Oct 01 22:56:48 volumio volumio-remote-updater[608]: [2024-10-01 22:56:48] [connect] Successful connection Oct 01 22:56:49 volumio volumio[9733]: info: Loading plugin "spop"... Oct 01 22:56:50 volumio volumio[9733]: info: Loading plugin "outputs"... Oct 01 22:56:50 volumio volumio[9733]: info: Loading plugin "albumart"... Oct 01 22:56:50 volumio sudo[9781]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:50 volumio volumio[9733]: info: Plugin example_plugin is not enabled Oct 01 22:56:50 volumio volumio[9733]: info: Loading plugin "inputs"... Oct 01 22:56:50 volumio volumio[9733]: info: Loading plugin "updater_comm"... Oct 01 22:56:51 volumio volumio[9733]: info: Plugin mpdemulation is not enabled Oct 01 22:56:51 volumio volumio[9733]: info: Loading plugin "rest_api"... Oct 01 22:56:51 volumio volumio[9733]: info: Loading plugin "websocket"... Oct 01 22:56:51 volumio volumio[9733]: info: Starting Socket.io Server version 2.3.0 Oct 01 22:56:51 volumio volumio[9733]: info: Loading plugin "motorized_fader_control"... Oct 01 22:56:51 volumio volumio[9733]: Forking 3 albumart workers Oct 01 22:56:51 volumio volumio[9733]: Starting albumart workers Oct 01 22:56:51 volumio volumio[9733]: Starting albumart workers Oct 01 22:56:51 volumio volumio[9733]: Starting albumart workers Oct 01 22:56:51 volumio volumio[9733]: info: motorized_fader_control plugin is starting... Oct 01 22:56:51 volumio volumio[9733]: info: Attempting to load configuration file from: /data/configuration/user_interface/motorized_fader_control/config.json Oct 01 22:56:51 volumio volumio[9733]: info: Configuration file loaded successfully. Oct 01 22:56:51 volumio volumio[9733]: info: motorized_fader_control plugin started successfully. Oct 01 22:56:51 volumio volumio[9733]: info: Loading i18n strings for locale de Oct 01 22:56:51 volumio volumio[9733]: Updating browse sources language Oct 01 22:56:51 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 22:56:51 volumio sudo[9837]: volumio : TTY=pts/3 ; PWD=/home/volumio/volumio-plugins-sources/motorized_fader_control ; USER=root ; COMMAND=/bin/journalctl -u volumio -f Oct 01 22:56:51 volumio sudo[9837]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::initPlayerControls Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: Express server listening on port 3000 Oct 01 22:56:52 volumio volumio[9733]: [Metrics] WebUI: 6s 851.40ms Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::resetVolumioState Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::getcurrentVolume Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 22:56:52 volumio volumio[9733]: verbose: New Socket.io Connection to 192.168.2.116 from 192.168.2.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 01 22:56:52 volumio volumio[9733]: verbose: New Socket.io Connection to 192.168.2.116 from 192.168.2.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 01 22:56:52 volumio volumio[9733]: verbose: New Socket.io Connection to volumio.local from 192.168.2.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 01 22:56:52 volumio volumio[9733]: verbose: New Socket.io Connection to volumio.local from 192.168.2.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 01 22:56:52 volumio volumio-remote-updater[608]: [2024-10-01 22:56:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727816208 101 Oct 01 22:56:52 volumio volumio[9733]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Oct 01 22:56:52 volumio volumio[9733]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: Reloading queue from file Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioGetQueue Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::getQueue Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getQueue Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 01 22:56:52 volumio volumio[9733]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Oct 01 22:56:52 volumio volumio[9733]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Oct 01 22:56:52 volumio volumio[9733]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:52 volumio volumio[9733]: info: Setting Device type: Raspberry PI Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::setRepeat null single undefined Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::pushState Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::setRandom null Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::pushState Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:52 volumio volumio[9733]: info: Completed loading Core Plugins Oct 01 22:56:52 volumio volumio[9733]: info: Preparing to generate the ALSA configuration file Oct 01 22:56:52 volumio volumio[9733]: info: VolumeController:: Volume=undefined Mute =false Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::pushState Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::updateTrackBlock Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getTrackBlock Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 22:56:52 volumio volumio[9733]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 01 22:56:52 volumio volumio[9733]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Oct 01 22:56:52 volumio volumio[9733]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioGetQueue Oct 01 22:56:52 volumio volumio[9733]: info: CoreStateMachine::getQueue Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getQueue Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 01 22:56:52 volumio volumio[9733]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Oct 01 22:56:52 volumio volumio[9733]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Oct 01 22:56:52 volumio volumio[9733]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Oct 01 22:56:52 volumio volumio[9733]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 01 22:56:52 volumio volumio[9733]: info: Asound.conf file unchanged, so no further update is needed Oct 01 22:56:52 volumio volumio[9733]: info: Output device has changed, restarting MPD Oct 01 22:56:52 volumio sudo[9876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 01 22:56:52 volumio sudo[9876]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:52 volumio volumio[9733]: info: Output device has changed, restarting Shairport Sync Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 22:56:52 volumio sudo[9876]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:52 volumio sudo[9879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 01 22:56:52 volumio sudo[9879]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:52 volumio systemd[1]: Stopping Music Player Daemon... Oct 01 22:56:52 volumio volumio[9733]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 01 22:56:52 volumio volumio[9733]: info: ___________ START PLUGINS ___________ Oct 01 22:56:52 volumio volumio[9733]: info: ControllerMpd::onStart: Initializing MPD Oct 01 22:56:52 volumio volumio[9733]: info: Creating MPD Configuration file Oct 01 22:56:52 volumio sudo[9885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 01 22:56:52 volumio sudo[9885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 01 22:56:52 volumio volumio[9733]: info: [1727816212674] CoreMusicLibrary::Adding element Medienserver Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 22:56:52 volumio sudo[9885]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:52 volumio sudo[9888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 01 22:56:52 volumio sudo[9888]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 01 22:56:52 volumio volumio[9733]: info: [1727816212772] CoreMusicLibrary::Adding element Last_100 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 01 22:56:52 volumio volumio[9733]: info: [1727816212774] CoreMusicLibrary::Adding element Webradio Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 01 22:56:52 volumio volumio[9733]: info: Initializing BBC Radios Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 22:56:52 volumio volumio[9733]: info: Discovery: Getting this device information Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:56:52 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 01 22:56:52 volumio volumio[9733]: info: [1727816212888] CoreMusicLibrary::Adding element Jellyfin Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 22:56:52 volumio volumio[9733]: Cannot find translation for source Jellyfin Oct 01 22:56:52 volumio volumio[9733]: info: [jellyfin] Initialized plugin with device info: {"id":"671b27be-bfe2-48b6-8b33-fef9a4484a07","host":"http://192.168.2.116","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"","mute":false,"artist":"Black Country, New Road","track":"Intro","albumart":"http://192.168.2.158:8096/Items/32a53ed051b38217e6f09feb0af420e7/Images/Primary?maxWidth=500&maxHeight=500&quality=90"}} Oct 01 22:56:52 volumio volumio[9733]: info: Creating Spotify config file Oct 01 22:56:52 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:52 volumio volumio[9733]: info: Starting motorized_fader_control plugin... Oct 01 22:56:52 volumio volumio[9733]: info: motorized_fader_control: Cached current log level: verbose Oct 01 22:56:52 volumio volumio[9733]: info: Log level changed to: debug Oct 01 22:56:52 volumio volumio[9733]: info: logger,callbacks,pluginsRestEndpoints,standByHandler,dspSignalPathElements,sharedVars,musicLibrary,pluginManager,configManager,i18nStringsDefaults,i18nStrings,stateMachine,volumeControl,playListManager,platformspecific Oct 01 22:56:52 volumio volumio[9733]: info: #### motorized_fader_control: Setting up FaderController... Oct 01 22:56:52 volumio volumio[9733]: debug: #### motorized_fader_control: Fader Controller Configuration: Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Fader Count: 2 Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Message Delay: 0.001 Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: MIDI Log: false Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Value Log: false Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Move Log: true Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Fader Trim Map: {"1":[0,100],"2":[0,100]} Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Speed High: 100 Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Speed Medium: 50 Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Speed Low: 10 Oct 01 22:56:52 volumio volumio[9733]: debug: motorized_fader_control: Calibration on Start: true Oct 01 22:56:52 volumio volumio[9733]: info: motorized_fader_control: FaderController initialized successfully. Oct 01 22:56:52 volumio volumio[9733]: info: motorized_fader_control: Trim map set successfully. Oct 01 22:56:52 volumio volumio[9733]: debug: ###### motorized_fader_control: Plugin setup completed successfully. Oct 01 22:56:52 volumio volumio[9733]: info: ###### motorized_fader_control: Starting... Oct 01 22:56:52 volumio volumio[9733]: info: #### motorized_fader_control: Starting Fader Controller... Oct 01 22:56:52 volumio volumio[9733]: info: ### Attempt 1 to initialize SerialPort: /dev/ttyUSB0 at baud rate: 1000000 Oct 01 22:56:52 volumio volumio[9733]: info: ### SerialPort initialized. Oct 01 22:56:52 volumio volumio[9733]: info: Volumio Calling Home Oct 01 22:56:53 volumio volumio[9733]: debug: ### Starting the FaderController... Oct 01 22:56:53 volumio volumio[9733]: info: Discovery: adding 671b27be-bfe2-48b6-8b33-fef9a4484a07 Oct 01 22:56:53 volumio volumio[9733]: info: Discovery: Found device Volumio Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:56:53 volumio volumio[9733]: debug: CoreStateMachine::getState Oct 01 22:56:53 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:53 volumio volumio[9733]: debug: API:pushMultiroomDevices Oct 01 22:56:53 volumio volumio[9733]: debug: SerialPort is opened: /dev/ttyUSB0 Oct 01 22:56:53 volumio volumio[9733]: verbose: New Socket.io Connection to 192.168.2.116 from 192.168.2.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 01 22:56:53 volumio volumio[9733]: info: MPD Permissions set Oct 01 22:56:53 volumio volumio[9733]: info: MPD Permissions set Oct 01 22:56:53 volumio volumio[9733]: info: VolumeController:: Volume=undefined Mute =false Oct 01 22:56:53 volumio volumio[9733]: debug: No callbacks for volumioupdatevolume Oct 01 22:56:53 volumio volumio[9733]: info: CoreStateMachine::pushState Oct 01 22:56:53 volumio volumio[9733]: debug: CoreStateMachine::getState Oct 01 22:56:53 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::volumioPushState Oct 01 22:56:53 volumio volumio[9733]: debug: API:pushState Oct 01 22:56:53 volumio volumio[9733]: debug: No callbacks for volumioPushState Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 01 22:56:53 volumio volumio[9733]: info: [jellyfin-poller] Polled http://192.168.2.158:8096/: online Oct 01 22:56:53 volumio volumio[9733]: info: Spotify config file written Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::volumioGetVisibleSources Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:56:53 volumio volumio[9733]: debug: CoreStateMachine::getState Oct 01 22:56:53 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 01 22:56:53 volumio volumio[9733]: info: Received Get System Info Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 22:56:53 volumio volumio[9733]: info: Discovery: Getting this device information Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:56:53 volumio volumio[9733]: debug: CoreStateMachine::getState Oct 01 22:56:53 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:56:53 volumio volumio[9733]: debug: CoreStateMachine::getState Oct 01 22:56:53 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:53 volumio volumio[9733]: info: Listing playlists Oct 01 22:56:53 volumio volumio[9733]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 01 22:56:53 volumio sudo[9918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 01 22:56:53 volumio sudo[9918]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:53 volumio systemd[1]: Stopping go-librespot Daemon... Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 01 22:56:53 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 01 22:56:53 volumio systemd[1]: Stopped go-librespot Daemon. Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 22:56:53 volumio systemd[1]: Started go-librespot Daemon. Oct 01 22:56:53 volumio sudo[9918]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:53 volumio go-librespot[9925]: Librespot-go daemon starting... Oct 01 22:56:53 volumio volumio[9733]: info: Starting Shairport Sync Oct 01 22:56:53 volumio volumio[9733]: info: Starting Shairport Sync Oct 01 22:56:53 volumio volumio[9733]: info: Starting Shairport Sync Oct 01 22:56:53 volumio sudo[9931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 22:56:53 volumio sudo[9933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 22:56:53 volumio sudo[9931]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:53 volumio sudo[9933]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:56:53 volumio volumio[9733]: debug: CoreStateMachine::getState Oct 01 22:56:53 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:56:53 volumio sudo[9941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 22:56:53 volumio sudo[9941]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 01 22:56:53 volumio volumio[9733]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 01 22:56:53 volumio volumio[9733]: SPOTIFY: BQAPxSCSH2PPLn_PoBDv0QEAwNPYlLk4a9cwXHtaIhLLKDPFNrOH5lhOE7mzDdi8DImSKOUjP9oKx4LMD2AwpFMwdAmFePfm_5RrlUTot2sWDMVqXw2-5haAxJES7B2OzSFJRM59ezz6cbkgmrXKTuvBgJE8Y8zXLwuYB-Ffb64l5RJkk2PkcNxLD80YqxhYmGR3vGAgZyuZFBnSGtAvkPpFyBw4X8BMhLlALfzhEOrO8iaIF8EmIow Oct 01 22:56:53 volumio volumio[9733]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 01 22:56:53 volumio volumio[9733]: info: New Spotify access token = BQAPxSCSH2PPLn_PoBDv0QEAwNPYlLk4a9cwXHtaIhLLKDPFNrOH5lhOE7mzDdi8DImSKOUjP9oKx4LMD2AwpFMwdAmFePfm_5RrlUTot2sWDMVqXw2-5haAxJES7B2OzSFJRM59ezz6cbkgmrXKTuvBgJE8Y8zXLwuYB-Ffb64l5RJkk2PkcNxLD80YqxhYmGR3vGAgZyuZFBnSGtAvkPpFyBw4X8BMhLlALfzhEOrO8iaIF8EmIow Oct 01 22:56:53 volumio volumio[9733]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 01 22:56:53 volumio volumio[9733]: SPOTIFY: User informations: {"display_name":"lemur30","external_urls":{"spotify":"https://open.spotify.com/user/lemur30"},"href":"https://api.spotify.com/v1/users/lemur30","id":"lemur30","images":[{"url":"https://i.scdn.co/image/ab67757000003b82fed857b2b5289d2be781bc6c","height":64,"width":64},{"url":"https://i.scdn.co/image/ab6775700000ee85fed857b2b5289d2be781bc6c","height":300,"width":300}],"type":"user","uri":"spotify:user:lemur30","followers":{"href":null,"total":11},"country":"DE","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"lemur30@gmx.de"} Oct 01 22:56:53 volumio volumio[9733]: info: Spotify Successfully logged in Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 01 22:56:53 volumio volumio[9733]: info: [1727816213695] CoreMusicLibrary::Adding element Spotify Oct 01 22:56:53 volumio volumio[9733]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 22:56:53 volumio volumio[9733]: Cannot find translation for source Jellyfin Oct 01 22:56:53 volumio volumio[9733]: Cannot find translation for source Spotify Oct 01 22:56:53 volumio volumio[9733]: debug: API:emitFavourites Oct 01 22:56:53 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 01 22:56:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 01 22:56:53 volumio go-librespot[9925]: time="2024-10-01T22:56:53+02:00" level=info msg="generated new device id: 0b782960708adbbd6d96dee7584f0e3c8c92b7a1" Oct 01 22:56:53 volumio go-librespot[9925]: time="2024-10-01T22:56:53+02:00" level=debug msg="stored credentials found for lemur30" Oct 01 22:56:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 01 22:56:53 volumio sudo[9933]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:53 volumio sudo[9941]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:53 volumio sudo[9931]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:53 volumio volumio[9733]: info: Shairport-Sync Started Oct 01 22:56:53 volumio volumio[9733]: Error adding Membership: Error: addMembership EINVAL Oct 01 22:56:53 volumio volumio[9733]: info: Shairport-Sync Started Oct 01 22:56:53 volumio volumio[9733]: info: Shairport-Sync Started Oct 01 22:56:53 volumio volumio[9733]: info: Volumio called home Oct 01 22:56:54 volumio go-librespot[9925]: time="2024-10-01T22:56:54+02:00" level=debug msg="obtained new client token: AAAyr2vc5Rm8HL16c7tbou8r9uUXXoUytjPRhqpQqMc/1PTAh/sxruuZRH05zniBneBTjHzcStB8q4STJ0GB/3ZWuoP7ChQDQQ9m/QN8A9mHzn69d5FWY1N+ZAYf8zoeRCga0WGU4zlhJGw+VUukZ8eBo4JilVG/wN2Jq7yMQito8J04Brg0cvAjfhYLwmYi8OKEJ+PwlazJbS/A+d5g/D6weNuGCFWn2F8VTAO4UV/lONx3EePoJtYIuHNxTg==" Oct 01 22:56:54 volumio go-librespot[9925]: time="2024-10-01T22:56:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 01 22:56:54 volumio go-librespot[9925]: time="2024-10-01T22:56:54+02:00" level=debug msg="completed keyexchange" Oct 01 22:56:54 volumio systemd[1]: mpd.service: Succeeded. Oct 01 22:56:54 volumio systemd[1]: Stopped Music Player Daemon. Oct 01 22:56:54 volumio systemd[1]: Starting Music Player Daemon... Oct 01 22:56:54 volumio volumio[9733]: debug: MIDI DATA: TYPE: PROGRAM_CHANGE CHANNEL: false DATA1: 160 DATA2: 102 Oct 01 22:56:54 volumio volumio[9733]: debug: Waiting for MIDI device ready... Oct 01 22:56:54 volumio volumio[9733]: info: MIDI device is ready Oct 01 22:56:54 volumio volumio[9733]: info: ### Calibrating Faders: OLD FADERCALIBRATION: 0,1,2,3 Oct 01 22:56:54 volumio volumio[9733]: debug: Moving Faders: 0,1,2,3 to 100,100,100,100 with speed: 50,50,50,50 Interrupting: false Oct 01 22:56:54 volumio volumio[9733]: debug: ProgressionsDict sent analysis (faderIdx):(AmountPositions): 0: 201, 1: 201, 2: 201, 3: 201 Oct 01 22:56:54 volumio volumio[9733]: debug: MIDI DATA: TYPE: PROGRAM_CHANGE CHANNEL: false DATA1: 160 DATA2: 50 Oct 01 22:56:54 volumio sudo[9961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 01 22:56:54 volumio sudo[9961]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:54 volumio sudo[9961]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:54 volumio go-librespot[9925]: time="2024-10-01T22:56:54+02:00" level=debug msg="completed challenge" Oct 01 22:56:54 volumio go-librespot[9925]: time="2024-10-01T22:56:54+02:00" level=debug msg="authenticated as lemur30" Oct 01 22:56:54 volumio go-librespot[9925]: time="2024-10-01T22:56:54+02:00" level=debug msg="authenticated as lemur30" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="dealer connection opened" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 505" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="received connection id: MGU0MThmNTYtZjFlNy00YjA0LTgwMjYtNzBmNjgwNzY3NzM1K2RlYWxlcit0Y3A6Ly8wYWNhNThmMS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNDhFNEYzQjE2REFFMDdBMTUwNUEyRTk1N0NBM0NCQzc4RTBBRkZENDQxNUM4NDc5MzNGMzQyNUU2MjMyRTExRg==" Oct 01 22:56:55 volumio go-librespot[9925]: time="2024-10-01T22:56:55+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 01 22:56:55 volumio volumio[9733]: debug: FADER MOVE PROTOCOL: Oct 01 22:56:55 volumio volumio[9733]: Moved Faders: [0,1,2,3] Oct 01 22:56:55 volumio volumio[9733]: StartPoints: [100,100,100,100] Oct 01 22:56:55 volumio volumio[9733]: Targets: [100,100,100,100] Oct 01 22:56:55 volumio volumio[9733]: RampStartActual: [0,0,0,0] Oct 01 22:56:55 volumio volumio[9733]: RampEndActual: [100,100,100,100] Oct 01 22:56:55 volumio volumio[9733]: Speeds: [50,50,50,50] Oct 01 22:56:55 volumio volumio[9733]: StepSize: [0.4975124378109453,0.4975124378109453,0.4975124378109453,0.4975124378109453] Oct 01 22:56:55 volumio volumio[9733]: Steps: [201,201,201,201] Oct 01 22:56:55 volumio volumio[9733]: Duration: 977ms Oct 01 22:56:55 volumio volumio[9733]: FaderInfo: Oct 01 22:56:55 volumio volumio[9733]: Fader 0: FADER INFO: index: 0 position: 16383 progression: 100 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:55 volumio volumio[9733]: Fader 1: FADER INFO: index: 1 position: 16383 progression: 100 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:55 volumio volumio[9733]: Fader 2: FADER INFO: index: 2 position: 16383 progression: 100 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:55 volumio volumio[9733]: Fader 3: FADER INFO: index: 3 position: 16383 progression: 100 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:56:55 volumio volumio[9733]: debug: Moving Faders: 0,1,2,3 to 0,0,0,0 with speed: 10,10,10,10 Interrupting: false Oct 01 22:56:55 volumio volumio[9733]: debug: ProgressionsDict sent analysis (faderIdx):(AmountPositions): 0: 1001, 1: 1001, 2: 1001, 3: 1001 Oct 01 22:56:55 volumio mpd[9968]: Oct 01 22:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 01 22:56:55 volumio systemd[1]: Started Music Player Daemon. Oct 01 22:56:55 volumio sudo[9879]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:55 volumio sudo[9888]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:55 volumio volumio[9733]: debug: API:printToastMessage Oct 01 22:56:55 volumio volumio[9733]: error: MPD error: The expression evaluated to a falsy value: Oct 01 22:56:55 volumio volumio[9733]: assert.ok(self.idling) Oct 01 22:56:55 volumio volumio[9733]: error: The expression evaluated to a falsy value: Oct 01 22:56:55 volumio volumio[9733]: assert.ok(self.idling) Oct 01 22:56:55 volumio volumio[9733]: error: updateQueue error: null Oct 01 22:56:55 volumio volumio[9733]: info: MPD running with PID9968 Oct 01 22:56:55 volumio volumio[9733]: ,establishing connection Oct 01 22:56:55 volumio volumio[9733]: error: updateQueue error: null Oct 01 22:56:56 volumio volumio[9733]: info: go-librespot daemon successfully initialized Oct 01 22:56:57 volumio sudo[9978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 22:56:57 volumio sudo[9978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:57 volumio sudo[9978]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:57 volumio sudo[9980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 22:56:57 volumio sudo[9980]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:57 volumio sudo[9980]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:57 volumio sudo[9984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 01 22:56:57 volumio sudo[9984]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 22:56:57 volumio sudo[9984]: pam_unix(sudo:session): session closed for user root Oct 01 22:56:57 volumio volumio[9733]: info: Upmpdcli Daemon Started Oct 01 22:56:59 volumio volumio[9733]: info: Initializing connection to go-librespot Websocket Oct 01 22:56:59 volumio go-librespot[9925]: time="2024-10-01T22:56:59+02:00" level=debug msg="new websocket client" Oct 01 22:56:59 volumio volumio[9733]: info: Connection to go-librespot Websocket established Oct 01 22:57:00 volumio volumio[9733]: debug: FADER MOVE PROTOCOL: Oct 01 22:57:00 volumio volumio[9733]: Moved Faders: [0,1,2,3] Oct 01 22:57:00 volumio volumio[9733]: StartPoints: [0,0,0,0] Oct 01 22:57:00 volumio volumio[9733]: Targets: [0,0,0,0] Oct 01 22:57:00 volumio volumio[9733]: RampStartActual: [100,100,100,100] Oct 01 22:57:00 volumio volumio[9733]: RampEndActual: [0,0,0,0] Oct 01 22:57:00 volumio volumio[9733]: Speeds: [10,10,10,10] Oct 01 22:57:00 volumio volumio[9733]: StepSize: [0.0999000999000999,0.0999000999000999,0.0999000999000999,0.0999000999000999] Oct 01 22:57:00 volumio volumio[9733]: Steps: [1001,1001,1001,1001] Oct 01 22:57:00 volumio volumio[9733]: Duration: 4609ms Oct 01 22:57:00 volumio volumio[9733]: FaderInfo: Oct 01 22:57:00 volumio volumio[9733]: Fader 0: FADER INFO: index: 0 position: 0 progression: 0 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:57:00 volumio volumio[9733]: Fader 1: FADER INFO: index: 1 position: 0 progression: 0 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:57:00 volumio volumio[9733]: Fader 2: FADER INFO: index: 2 position: 0 progression: 0 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:57:00 volumio volumio[9733]: Fader 3: FADER INFO: index: 3 position: 0 progression: 0 touch: false echo_mode: false ProgressionMap: 0,100 MovementSpeedFactor: 1 Oct 01 22:57:00 volumio volumio[9733]: info: ### FaderController started! Oct 01 22:57:00 volumio volumio[9733]: info: #### motorized_fader_control: Setting up fader controller touch callbacks... Oct 01 22:57:00 volumio volumio[9733]: info: motorized_fader_control: Configuring fader 0 for seek input Oct 01 22:57:00 volumio volumio[9733]: info: motorized_fader_control: Setting seek callbacks for fader 0 Oct 01 22:57:00 volumio volumio[9733]: info: #### motorized_fader_control: Callbacks set successfully for fader 0 of type seek Oct 01 22:57:00 volumio volumio[9733]: info: motorized_fader_control: Configuring fader 1 for volume input Oct 01 22:57:00 volumio volumio[9733]: info: motorized_fader_control: Setting volume callbacks for fader 1 Oct 01 22:57:00 volumio volumio[9733]: info: #### motorized_fader_control: Callbacks set successfully for fader 1 of type volume Oct 01 22:57:00 volumio volumio[9733]: info: #### motorized_fader_control: Fader controller touch callbacks setup complete. Oct 01 22:57:00 volumio volumio[9733]: debug: motorized_fader_control: FaderController touch callbacks set up successfully. Oct 01 22:57:00 volumio volumio[9733]: info: #### motorized_fader_control: Fader Controller started successfully Oct 01 22:57:00 volumio volumio[9733]: info: Using WebSocket: true Oct 01 22:57:00 volumio volumio[9733]: info: ## motorized_fader_control: Setting up WebSocket connection... Oct 01 22:57:00 volumio volumio[9733]: info: ###### motorized_fader_control: Started successfully. Oct 01 22:57:00 volumio volumio[9733]: info: motorized_fader_control plugin started successfully. Oct 01 22:57:00 volumio volumio[9733]: info: Completed starting Core Plugins Oct 01 22:57:00 volumio volumio[9733]: info: ------------------------------------------- Oct 01 22:57:00 volumio volumio[9733]: info: ----- MyVolumio plugins startup ---- Oct 01 22:57:00 volumio volumio[9733]: info: ------------------------------------------- Oct 01 22:57:00 volumio volumio[9733]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 01 22:57:00 volumio volumio[9733]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 01 22:57:00 volumio volumio[9733]: info: ## motorized_fader_control: WebSocket connected Oct 01 22:57:00 volumio volumio[9733]: info: CoreCommandRouter::volumioGetState Oct 01 22:57:00 volumio volumio[9733]: debug: CoreStateMachine::getState Oct 01 22:57:00 volumio volumio[9733]: info: CorePlayQueue::getTrack 0 Oct 01 22:57:00 volumio volumio[9733]: info: motorized_fader_control: Received pushState update Oct 01 22:57:00 volumio volumio[9733]: debug: #### motorized_fader_control: State cached: {"status":"stop","position":0,"title":"Intro","artist":"Black Country, New Road","album":"Ants From Up There (Deluxe)","albumart":"http://192.168.2.158:8096/Items/32a53ed051b38217e6f09feb0af420e7/Images/Primary?maxWidth=500&maxHeight=500&quality=90","uri":"jellyfin/karim@d94cd56570f04007acd1bdf67327cd78/song@songId=32a53ed051b38217e6f09feb0af420e7","trackType":"flac","seek":0,"duration":54,"samplerate":"96 kHz","bitdepth":"24 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","updatedb":false,"volatile":false,"service":"jellyfin"} Oct 01 22:57:00 volumio volumio[9733]: info: #### motorized_fader_control: handleStateUpdate Oct 01 22:57:00 volumio volumio[9733]: info: #### motorized_fader_control: getVolume triggered Oct 01 22:57:00 volumio volumio[9733]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 22:57:00 volumio volumio[9733]: TypeError: state.get is not a function Oct 01 22:57:00 volumio volumio[9733]: at motorizedFaderControl.getVolume (/data/plugins/user_interface/motorized_fader_control/index.js:833:26) Oct 01 22:57:00 volumio volumio[9733]: at motorizedFaderControl.handleStateUpdate (/data/plugins/user_interface/motorized_fader_control/index.js:575:30) Oct 01 22:57:00 volumio volumio[9733]: at motorizedFaderControl.onPushState (/data/plugins/user_interface/motorized_fader_control/index.js:508:10) Oct 01 22:57:00 volumio volumio[9733]: at Socket. (/data/plugins/user_interface/motorized_fader_control/index.js:419:14) Oct 01 22:57:00 volumio volumio[9733]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:57:00 volumio volumio[9733]: at Socket.onevent (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:278:10) Oct 01 22:57:00 volumio volumio[9733]: at Socket.onpacket (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/socket.js:236:12) Oct 01 22:57:00 volumio volumio[9733]: at Manager. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:57:00 volumio volumio[9733]: at Manager.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:57:00 volumio volumio[9733]: at Manager.ondecoded (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:349:8) Oct 01 22:57:00 volumio volumio[9733]: at Decoder. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:57:00 volumio volumio[9733]: at Decoder.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:57:00 volumio volumio[9733]: at Decoder.add (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-parser/index.js:254:12) Oct 01 22:57:00 volumio volumio[9733]: at Manager.ondata (/data/plugins/user_interface/motorized_fader_control/node_modules/socket.io-client/lib/manager.js:339:16) Oct 01 22:57:00 volumio volumio[9733]: at Socket. (/data/plugins/user_interface/motorized_fader_control/node_modules/component-bind/index.js:21:15) Oct 01 22:57:00 volumio volumio[9733]: at Socket.Emitter.emit (/data/plugins/user_interface/motorized_fader_control/node_modules/component-emitter/index.js:145:20) Oct 01 22:57:00 volumio volumio[9733]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 22:57:00 volumio sudo[10008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-01 22:56 Oct 01 22:57:00 volumio sudo[10008]: 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="35b821ff7c588ea2ff28d7b2bc9bb02e49e3ab6c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8ef80245b1f7b577b29fe08b77efdc88d53369e5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 18 Sep 2024 02:17:42 PM CEST" VOLUMIO_VERSION="3.758" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cfa71b4386e95e4d11d00ae35602129a"