-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-11-23 12:53:42 CET. -- Nov 23 12:52:04 volumio volumio[1018]: info: [ASDebug] Port: 24 Nov 23 12:52:04 volumio volumio[1018]: info: [ASDebug] Inverted: false Nov 23 12:52:04 volumio volumio[1018]: info: [ASDebug] Delay: 720 Nov 23 12:52:07 volumio volumio[1018]: info: Disabling plugin backup_restore Nov 23 12:52:07 volumio volumio[1018]: info: Done. Nov 23 12:52:09 volumio volumio[1018]: info: Enabling plugin ampswitch Nov 23 12:52:09 volumio volumio[1018]: info: Loading plugin "ampswitch"... Nov 23 12:52:09 volumio volumio[1018]: info: Applying required configuration parameters for plugin ampswitch Nov 23 12:52:09 volumio volumio[1018]: info: PLUGIN START: ampswitch Nov 23 12:52:09 volumio volumio[1018]: info: Done. Nov 23 12:52:09 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Nov 23 12:52:09 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:09 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:09 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:10 volumio volumio[1018]: info: [ASDebug] Port: 24 Nov 23 12:52:10 volumio volumio[1018]: info: [ASDebug] Inverted: false Nov 23 12:52:10 volumio volumio[1018]: info: [ASDebug] Delay: 720 Nov 23 12:52:18 volumio volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 12:52:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 12:52:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 12:52:20 volumio volumio[1018]: info: Discovery: Getting this device information Nov 23 12:52:20 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Nov 23 12:52:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 12:52:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 6 Nov 23 12:52:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Nov 23 12:52:20 volumio volumio[1018]: info: Prefetching next song Nov 23 12:52:20 volumio volumio[1018]: info: [1732362740759] ControllerQobuz::prefetch Nov 23 12:52:20 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/35543623" Nov 23 12:52:20 volumio volumio[1018]: STREAMING PROXY: Handling url /?data=qobuz://song/35543623 Nov 23 12:52:20 volumio volumio[1018]: info: Executing endpoint getStreamUrlqobuz Nov 23 12:52:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 23 12:52:21 volumio volumio[1018]: info: getStreamUrl took 689 milliseconds Nov 23 12:52:21 volumio volumio[1018]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1291004&eid=35543623&fmt=5&profile=raw&app_id=539451548&cid=2437301&etsp=1732366341&hmac=GD-U_4puv4SXg6lJTOW3NqQP8vk Nov 23 12:52:22 volumio volumio[1018]: STREAMING PROXY: Response: 200, length: 11321468 Nov 23 12:52:22 volumio volumio[1018]: STREAMING PROXY: Client dropped request, destroying Nov 23 12:52:22 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/35543623" Nov 23 12:52:22 volumio volumio[1018]: info: Nov 23 12:52:22 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Nov 23 12:52:22 volumio volumio[1018]: info: Ignoring MPD Status Update Nov 23 12:52:22 volumio volumio[1018]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/35543623" took 3 milliseconds Nov 23 12:52:22 volumio volumio[1018]: STREAMING PROXY: Handling url /?data=qobuz://song/35543623 Nov 23 12:52:22 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 12:52:22 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 23 12:52:22 volumio volumio[1018]: info: ------------------------------ 5ms Nov 23 12:52:22 volumio volumio[1018]: info: sendMpdCommand consume 1 took 2 milliseconds Nov 23 12:52:22 volumio volumio[1018]: info: Executing endpoint getStreamUrlqobuz Nov 23 12:52:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 23 12:52:23 volumio volumio[1018]: info: getStreamUrl took 905 milliseconds Nov 23 12:52:23 volumio volumio[1018]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1291004&eid=35543623&fmt=5&profile=raw&app_id=539451548&cid=2437301&etsp=1732366342&hmac=z2wDhwyMUClsjuplaBAlGlwRNp4 Nov 23 12:52:23 volumio volumio[1018]: STREAMING PROXY: Response: 200, length: 11321468 Nov 23 12:52:23 volumio volumio[1018]: info: Nov 23 12:52:23 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Nov 23 12:52:23 volumio volumio[1018]: info: Ignoring MPD Status Update Nov 23 12:52:23 volumio volumio[1018]: info: Nov 23 12:52:23 volumio volumio[1018]: ---------------------------- MPD announces state update: player Nov 23 12:52:23 volumio volumio[1018]: info: ControllerMpd::getState Nov 23 12:52:23 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Nov 23 12:52:23 volumio volumio[1018]: info: ------------------------------ 3ms Nov 23 12:52:25 volumio volumio[1018]: info: sendMpdCommand status took 1154 milliseconds Nov 23 12:52:25 volumio volumio[1018]: verbose: ControllerMpd::parseState Nov 23 12:52:25 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 12:52:25 volumio volumio[1018]: info: Nov 23 12:52:25 volumio volumio[1018]: ---------------------------- MPD announces state update: player Nov 23 12:52:25 volumio volumio[1018]: info: ControllerMpd::getState Nov 23 12:52:25 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Nov 23 12:52:25 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 23 12:52:25 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Nov 23 12:52:25 volumio volumio[1018]: info: ControllerMpd::pushState Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Nov 23 12:52:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 6 Nov 23 12:52:25 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35543623","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/35543623","trackType":"qobuz"} Nov 23 12:52:25 volumio volumio[1018]: verbose: CURRENT POSITION 6 Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Nov 23 12:52:25 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::pushState Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::pushState Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output Nov 23 12:52:25 volumio volumio[1018]: info: ------------------------------ 1232ms Nov 23 12:52:25 volumio volumio[1018]: info: sendMpdCommand status took 73 milliseconds Nov 23 12:52:25 volumio volumio[1018]: verbose: ControllerMpd::parseState Nov 23 12:52:25 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [GPIO_Control] Status changed: play Nov 23 12:52:25 volumio volumio[1018]: info: [GPIO_Control] Status changed: play Nov 23 12:52:25 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 12:52:25 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 12:52:25 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 32 milliseconds Nov 23 12:52:25 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Nov 23 12:52:25 volumio volumio[1018]: info: ControllerMpd::pushState Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Nov 23 12:52:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 6 Nov 23 12:52:25 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35543623","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/35543623","trackType":"qobuz"} Nov 23 12:52:25 volumio volumio[1018]: verbose: CURRENT POSITION 6 Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Nov 23 12:52:25 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::pushState Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::pushState Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output Nov 23 12:52:25 volumio volumio[1018]: info: ------------------------------ 172ms Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Nov 23 12:52:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [GPIO_Control] Status changed: play Nov 23 12:52:25 volumio volumio[1018]: info: [GPIO_Control] Status changed: play Nov 23 12:52:25 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 12:52:25 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 12:52:25 volumio volumio[1018]: info: CoreStateMachine::pushState Nov 23 12:52:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 12:52:25 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Nov 23 12:52:25 volumio volumio[1018]: info: MRS: Pushing multiroomSync output Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [ASDebug] CurState: play PrevState: play Nov 23 12:52:25 volumio volumio[1018]: info: [GPIO_Control] Status changed: play Nov 23 12:52:25 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 12:52:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 12:52:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 12:52:50 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 12:52:50 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 12:52:50 volumio volumio[1018]: info: Discovery: Getting this device information Nov 23 12:52:50 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Nov 23 12:52:50 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Nov 23 12:52:50 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 12:52:58 volumio volumio[1018]: info: Starting Uninstall of plugin system_hardware - music_services_shield Nov 23 12:52:58 volumio volumio[1018]: info: Uninstalling plugin music_services_shield Nov 23 12:52:58 volumio volumio[1018]: info: Disabling plugin music_services_shield Nov 23 12:52:58 volumio volumio[1018]: info: Checking if uninstall.sh is present Nov 23 12:52:58 volumio volumio[1018]: info: Executing uninstall.sh Nov 23 12:52:58 volumio sudo[3524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 23 12:52:58 volumio sudo[3524]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 12:52:58 volumio sudo[3527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/removeservice.sh Nov 23 12:52:58 volumio sudo[3527]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 12:52:59 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 23 12:52:59 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 23 12:52:59 volumio sudo[3530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/music_services_shield/uninstall.sh Nov 23 12:52:59 volumio sudo[3530]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 12:52:59 volumio systemd[1]: Reloading. Nov 23 12:52:59 volumio sudo[3544]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/removeservice.sh Nov 23 12:52:59 volumio sudo[3544]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 12:52:59 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 23 12:52:59 volumio sudo[3566]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/cset shield -r Nov 23 12:52:59 volumio sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 12:52:59 volumio sudo[3566]: pam_unix(sudo:session): session closed for user root Nov 23 12:53:00 volumio sudo[3524]: pam_unix(sudo:session): session closed for user root Nov 23 12:53:00 volumio volumio[1018]: info: succeeded Nov 23 12:53:00 volumio systemd[1]: Reloading. Nov 23 12:53:00 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 23 12:53:01 volumio sudo[3527]: pam_unix(sudo:session): session closed for user root Nov 23 12:53:01 volumio systemd[1]: Reloading. Nov 23 12:53:01 volumio volumio[1018]: info: succeeded Nov 23 12:53:01 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 23 12:53:02 volumio sudo[3544]: pam_unix(sudo:session): session closed for user root Nov 23 12:53:02 volumio sudo[3604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get remove -y cpuset Nov 23 12:53:02 volumio sudo[3604]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 12:53:11 volumio sudo[3604]: pam_unix(sudo:session): session closed for user root Nov 23 12:53:11 volumio sudo[3530]: pam_unix(sudo:session): session closed for user root Nov 23 12:53:11 volumio volumio[1018]: info: Uninstall script completed Nov 23 12:53:11 volumio volumio[1018]: info: Removing plugin music_services_shield from configuration Nov 23 12:53:17 volumio volumio[1018]: info: Successfully removed music_services_shield configuration files Nov 23 12:53:17 volumio volumio[1018]: info: Plugin folders cleanup Nov 23 12:53:17 volumio volumio[1018]: info: Scanning into folder /volumio/app/plugins/ Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category audio_interface Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category miscellanea Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category music_service Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category plugins.json Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category system_controller Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category user_interface Nov 23 12:53:17 volumio volumio[1018]: info: Scanning into folder /data/plugins/ Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category music_service Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category system_controller Nov 23 12:53:17 volumio volumio[1018]: info: Scanning category system_hardware Nov 23 12:53:17 volumio volumio[1018]: info: Cleaning folder for music_services_shield Nov 23 12:53:17 volumio volumio[1018]: info: Plugin folders cleanup completed Nov 23 12:53:17 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 12:53:17 volumio volumio[1018]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"GPIO Control","name":"gpio_control","category":"system_hardware","version":"1.0.10","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MPD OLED","name":"mpd_oled","category":"system_hardware","version":"1.1.3","icon":"fa-television","isManuallyInstalled":false,"enabled":false,"active":false}] Nov 23 12:53:17 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 12:53:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 12:53:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 12:53:22 volumio volumio[1018]: info: Discovery: Getting this device information Nov 23 12:53:22 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Nov 23 12:53:22 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Nov 23 12:53:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 12:53:36 volumio volumio[1018]: info: [GPIO_Control] GPIOs destroyed Nov 23 12:53:36 volumio volumio[1018]: info: Disabling plugin gpio_control Nov 23 12:53:36 volumio volumio[1018]: info: Done. Nov 23 12:53:40 volumio volumio[1018]: info: CoreCommandRouter::volumioPause Nov 23 12:53:40 volumio volumio[1018]: info: CoreStateMachine::pause Nov 23 12:53:40 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Nov 23 12:53:40 volumio volumio[1018]: info: CoreStateMachine::servicePause Nov 23 12:53:40 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Nov 23 12:53:40 volumio volumio[1018]: info: CoreCommandRouter::servicePause Nov 23 12:53:40 volumio volumio[1018]: info: [1732362820608] ControllerQobuz::pause Nov 23 12:53:40 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 12:53:40 volumio volumio[1018]: info: ControllerMpd::pause Nov 23 12:53:40 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand pause Nov 23 12:53:40 volumio volumio[1018]: info: Nov 23 12:53:40 volumio volumio[1018]: ---------------------------- MPD announces state update: player Nov 23 12:53:40 volumio volumio[1018]: info: sendMpdCommand pause took 2 milliseconds Nov 23 12:53:40 volumio volumio[1018]: info: ControllerMpd::getState Nov 23 12:53:40 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Nov 23 12:53:40 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Nov 23 12:53:40 volumio volumio[1018]: verbose: ControllerMpd::parseState Nov 23 12:53:40 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 12:53:40 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 12:53:40 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Nov 23 12:53:40 volumio volumio[1018]: info: ControllerMpd::pushState Nov 23 12:53:40 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Nov 23 12:53:40 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Nov 23 12:53:40 volumio volumio[1018]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74297,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35543623","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/35543623","trackType":"qobuz"} Nov 23 12:53:40 volumio volumio[1018]: verbose: CURRENT POSITION 7 Nov 23 12:53:40 volumio volumio[1018]: info: CoreStateMachine::syncState stateService pause Nov 23 12:53:40 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus pause Nov 23 12:53:40 volumio volumio[1018]: info: CoreStateMachine::pushState Nov 23 12:53:40 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 12:53:40 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Nov 23 12:53:40 volumio volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Nov 23 12:53:40 volumio volumio[1018]: info: MRS: Pushing multiroomSync output Nov 23 12:53:40 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Nov 23 12:53:40 volumio volumio[1018]: info: ------------------------------ 43ms Nov 23 12:53:40 volumio volumio[1018]: info: [ASDebug] CurState: pause PrevState: play Nov 23 12:53:40 volumio volumio[1018]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 23 12:53:40 volumio volumio[1018]: info: [ASDebug] CurState: pause PrevState: play Nov 23 12:53:40 volumio volumio[1018]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 23 12:53:40 volumio volumio[1018]: info: [GPIO_Control] Status changed: pause Nov 23 12:53:40 volumio volumio[1018]: info: [GPIO_Control] Handling event: musicPause Nov 23 12:53:40 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 12:53:41 volumio volumio[1018]: info: [ASDebug] Togle GPIO: OFF Nov 23 12:53:41 volumio volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 12:53:41 volumio volumio[1018]: Error: EBADF: bad file descriptor, write Nov 23 12:53:41 volumio volumio[1018]: at Object.writeSync (fs.js:711:3) Nov 23 12:53:41 volumio volumio[1018]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 23 12:53:41 volumio volumio[1018]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Nov 23 12:53:41 volumio volumio[1018]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Nov 23 12:53:41 volumio volumio[1018]: at listOnTimeout (internal/timers.js:554:17) Nov 23 12:53:41 volumio volumio[1018]: at processTimers (internal/timers.js:497:7) { Nov 23 12:53:41 volumio volumio[1018]: errno: -9, Nov 23 12:53:41 volumio volumio[1018]: syscall: 'write', Nov 23 12:53:41 volumio volumio[1018]: code: 'EBADF' Nov 23 12:53:41 volumio volumio[1018]: } Nov 23 12:53:41 volumio volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 12:53:42 volumio sudo[3767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 12:52 Nov 23 12:53:42 volumio sudo[3767]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"