-- Logs begin at Wed 2024-09-04 17:21:23 CST, end at Fri 2024-09-06 20:01:31 CST. -- Sep 06 20:00:00 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 20:00:00 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 20:00:00 rpi3bplus volumio[794]: info: Discovery: Getting this device information Sep 06 20:00:00 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioGetState Sep 06 20:00:00 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 20:00:05 rpi3bplus kernel: hwmon hwmon1: Undervoltage detected! Sep 06 20:00:11 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Sep 06 20:00:11 rpi3bplus volumio[794]: info: [jellyfin-browse] browseUri: jellyfin Sep 06 20:00:11 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:12 rpi3bplus kernel: hwmon hwmon1: Voltage normalised Sep 06 20:00:12 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Sep 06 20:00:12 rpi3bplus volumio[794]: info: [jellyfin-browse] browseUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237 Sep 06 20:00:12 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:12 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:16 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Sep 06 20:00:16 rpi3bplus volumio[794]: info: [jellyfin-browse] browseUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79 Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=b1da88fb51968c3e4c0166c47d1f5041 Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=f441e3f711415bc36353be0e0951896a Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=4457ebc878b1d0ebd2c6b9799fa6402e Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=5d07962c927dcf5022b4068d8fbc0f33 Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=48042903121c3ba6ab253116f356717b Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=4457ebc878b1d0ebd2c6b9799fa6402e Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=5d07962c927dcf5022b4068d8fbc0f33 Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=f441e3f711415bc36353be0e0951896a Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=b1da88fb51968c3e4c0166c47d1f5041 Sep 06 20:00:17 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=1ad9a57742247583bddb8257f7803674 Sep 06 20:00:17 rpi3bplus volumio[794]: info: Exploding uri jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=4457ebc878b1d0ebd2c6b9799fa6402e in service jellyfin Sep 06 20:00:17 rpi3bplus volumio[794]: info: [jellyfin-browse] explodeUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=4457ebc878b1d0ebd2c6b9799fa6402e Sep 06 20:00:17 rpi3bplus volumio[794]: info: Exploding uri jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=5d07962c927dcf5022b4068d8fbc0f33 in service jellyfin Sep 06 20:00:17 rpi3bplus volumio[794]: info: [jellyfin-browse] explodeUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=5d07962c927dcf5022b4068d8fbc0f33 Sep 06 20:00:17 rpi3bplus volumio[794]: info: Exploding uri jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=f441e3f711415bc36353be0e0951896a in service jellyfin Sep 06 20:00:17 rpi3bplus volumio[794]: info: [jellyfin-browse] explodeUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=f441e3f711415bc36353be0e0951896a Sep 06 20:00:17 rpi3bplus volumio[794]: info: Exploding uri jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=b1da88fb51968c3e4c0166c47d1f5041 in service jellyfin Sep 06 20:00:17 rpi3bplus volumio[794]: info: [jellyfin-browse] explodeUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=b1da88fb51968c3e4c0166c47d1f5041 Sep 06 20:00:17 rpi3bplus volumio[794]: info: Exploding uri jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=1ad9a57742247583bddb8257f7803674 in service jellyfin Sep 06 20:00:17 rpi3bplus volumio[794]: info: [jellyfin-browse] explodeUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/song@songId=1ad9a57742247583bddb8257f7803674 Sep 06 20:00:20 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Sep 06 20:00:21 rpi3bplus volumio[794]: info: [jellyfin-browse] browseUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=5d07962c927dcf5022b4068d8fbc0f33 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=247033f0c9ff14f2962fba8840becf4f Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=2ebbe58743c94689ab63a2d0549702d0 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=c7cd56c6e2487b1a192cc7b18136bb15 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=5848a895245e53ea8d1784de0c14b525 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=200886dedcd479555bbc39f3c2302510 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=5a66d1adc1d8a38cb0195a6cbe881074 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=13c7c8fad74b926a8b8b3408d9484b4a Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=723096264d4a1c74c68daeb9b4246806 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=dff9b955c50be7db9f4b929e1bdfcef5 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preloading song: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1/song@songId=1ce2fa0e8f79f02f62dbf915660cf203 Sep 06 20:00:21 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:21 rpi3bplus volumio[794]: info: No valid Plugin REST Endpoint Sep 06 20:00:21 rpi3bplus volumio[794]: info: No valid Plugin REST Endpoint Sep 06 20:00:23 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::ClearQueue Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::stop Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::serviceStop Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreCommandRouter::serviceStop Sep 06 20:00:23 rpi3bplus volumio[794]: info: Airplay Stop Sep 06 20:00:23 rpi3bplus volumio[794]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 06 20:00:23 rpi3bplus volumio[794]: info: CorePlayQueue::clearPlayQueue Sep 06 20:00:23 rpi3bplus volumio[794]: info: CorePlayQueue::saveQueue Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushQueue Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::addQueueItems Sep 06 20:00:23 rpi3bplus volumio[794]: info: CorePlayQueue::addQueueItems Sep 06 20:00:23 rpi3bplus volumio[794]: info: Preload queue cleared Sep 06 20:00:23 rpi3bplus volumio[794]: info: Adding Item to queue: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1 Sep 06 20:00:23 rpi3bplus volumio[794]: info: Exploding uri jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1 in service jellyfin Sep 06 20:00:23 rpi3bplus volumio[794]: info: [jellyfin-browse] explodeUri: jellyfin/rex@63214e4e2fae484d86aaf589af034237/library@parentId=28e9960207c978c0d9aaefc8ae2d3a79/songs@albumId=b612ba5207f14afb23c604dac033bdf1 Sep 06 20:00:23 rpi3bplus sudo[2203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1084 Sep 06 20:00:23 rpi3bplus sudo[2203]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 20:00:23 rpi3bplus sudo[2203]: pam_unix(sudo:session): session closed for user root Sep 06 20:00:23 rpi3bplus volumio[794]: info: Shairport-Sync paused with USR2 Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushQueue Sep 06 20:00:23 rpi3bplus volumio[794]: info: CorePlayQueue::saveQueue Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::updateTrackBlock Sep 06 20:00:23 rpi3bplus volumio[794]: info: CorePlayQueue::getTrackBlock Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPlay Sep 06 20:00:23 rpi3bplus volumio[794]: verbose: UNSET VOLATILE: Service: airplay_emulation Sep 06 20:00:23 rpi3bplus volumio[794]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::play index 0 Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::stop Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::play index undefined Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 20:00:23 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:23 rpi3bplus volumio[794]: info: CoreStateMachine::startPlaybackTimer Sep 06 20:00:23 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:23 rpi3bplus volumio[794]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/rex@63214e4e2fae484d86aaf589af034237/song@songId=5d07962c927dcf5022b4068d8fbc0f33 Sep 06 20:00:23 rpi3bplus sudo[2207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1084 Sep 06 20:00:23 rpi3bplus sudo[2207]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 20:00:23 rpi3bplus sudo[2207]: pam_unix(sudo:session): session closed for user root Sep 06 20:00:23 rpi3bplus volumio[794]: info: [jellyfin-play] Stream URL for Get Over It: https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb Sep 06 20:00:23 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 20:00:23 rpi3bplus volumio[794]: info: sendMpdCommand stop took 4 milliseconds Sep 06 20:00:23 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand clear Sep 06 20:00:23 rpi3bplus volumio[794]: info: Shairport-Sync paused with USR2 Sep 06 20:00:23 rpi3bplus volumio[794]: info: Sep 06 20:00:23 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:23 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:23 rpi3bplus volumio[794]: info: sendMpdCommand clear took 6 milliseconds Sep 06 20:00:23 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand load "https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3" Sep 06 20:00:23 rpi3bplus volumio[794]: info: Sep 06 20:00:23 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:23 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:23 rpi3bplus volumio[794]: info: Sep 06 20:00:23 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:23 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:23 rpi3bplus volumio[794]: error: updateQueue error: null Sep 06 20:00:23 rpi3bplus volumio[794]: info: ------------------------------ 14ms Sep 06 20:00:23 rpi3bplus volumio[794]: error: Upnp client error: Error: This socket has been ended by the other party Sep 06 20:00:24 rpi3bplus volumio[794]: info: Restarting Shairport-Sync after stop Sep 06 20:00:24 rpi3bplus sudo[2211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 20:00:24 rpi3bplus sudo[2211]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 20:00:24 rpi3bplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 20:00:24 rpi3bplus shairport-sync[1084]: {"time":1725623951173,"response":"stopAirplayPlayback Success"} Sep 06 20:00:24 rpi3bplus systemd[1]: shairport-sync.service: Succeeded. Sep 06 20:00:24 rpi3bplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 20:00:24 rpi3bplus volumio[794]: info: Restarting Shairport-Sync after stop Sep 06 20:00:25 rpi3bplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 20:00:25 rpi3bplus sudo[2211]: pam_unix(sudo:session): session closed for user root Sep 06 20:00:25 rpi3bplus volumio[794]: info: Shairport-Sync retarted Sep 06 20:00:25 rpi3bplus sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 20:00:25 rpi3bplus sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 20:00:25 rpi3bplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 20:00:25 rpi3bplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 06 20:00:25 rpi3bplus systemd[1]: shairport-sync.service: Succeeded. Sep 06 20:00:25 rpi3bplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 20:00:25 rpi3bplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 20:00:25 rpi3bplus sudo[2218]: pam_unix(sudo:session): session closed for user root Sep 06 20:00:25 rpi3bplus volumio[794]: info: Shairport-Sync retarted Sep 06 20:00:25 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand addid "https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3" Sep 06 20:00:25 rpi3bplus volumio[794]: error: updateQueue error: null Sep 06 20:00:25 rpi3bplus volumio[794]: error: updateQueue error: null Sep 06 20:00:25 rpi3bplus volumio[794]: info: Sep 06 20:00:25 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:25 rpi3bplus volumio[794]: info: ------------------------------ 1986ms Sep 06 20:00:25 rpi3bplus volumio[794]: info: ------------------------------ 1984ms Sep 06 20:00:25 rpi3bplus volumio[794]: info: sendMpdCommand addid "https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3" took 7 milliseconds Sep 06 20:00:25 rpi3bplus volumio[794]: verbose: MPD COMMAND [object Object] Sep 06 20:00:25 rpi3bplus volumio[794]: verbose: MPD COMMAND [object Object] Sep 06 20:00:25 rpi3bplus volumio[794]: verbose: MPD COMMAND [object Object] Sep 06 20:00:25 rpi3bplus volumio[794]: info: Sep 06 20:00:25 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Sep 06 20:00:25 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Sep 06 20:00:25 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:25 rpi3bplus volumio[794]: info: ------------------------------ 22ms Sep 06 20:00:25 rpi3bplus volumio[794]: info: ------------------------------ 12ms Sep 06 20:00:25 rpi3bplus volumio[794]: info: ------------------------------ 10ms Sep 06 20:00:25 rpi3bplus volumio[794]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 06 20:00:25 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand play Sep 06 20:00:25 rpi3bplus volumio[794]: info: Sep 06 20:00:25 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Sep 06 20:00:25 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:25 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:25 rpi3bplus volumio[794]: info: ------------------------------ 16ms Sep 06 20:00:25 rpi3bplus volumio[794]: info: sendMpdCommand play took 10 milliseconds Sep 06 20:00:25 rpi3bplus volumio[794]: info: ------------------------------ 9ms Sep 06 20:00:25 rpi3bplus volumio[794]: info: ------------------------------ 13ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: [jellyfin-play]: Mark song Get Over It as played by Rex Zhang. Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 22 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 24 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 21 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 18 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:26 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 46 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 40 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 30 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 22 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 18 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus stop Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus play Sep 06 20:00:26 rpi3bplus volumio[794]: info: Received an update from plugin. extracting info from payload Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus play Sep 06 20:00:26 rpi3bplus volumio[794]: info: Received an update from plugin. extracting info from payload Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 149ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 194ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 192ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: Pushing Favourites {"favourite":false} Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:26 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces system playlist update Sep 06 20:00:26 rpi3bplus volumio[794]: info: Ignoring MPD Status Update Sep 06 20:00:26 rpi3bplus volumio[794]: info: Sep 06 20:00:26 rpi3bplus volumio[794]: ---------------------------- MPD announces state update: player Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::getState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand status Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 191 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 194ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 188 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 178 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 177 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 175 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 176 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 30ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 26 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 25ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand status took 22 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseState Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus play Sep 06 20:00:26 rpi3bplus volumio[794]: info: Received an update from plugin. extracting info from payload Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus play Sep 06 20:00:26 rpi3bplus volumio[794]: info: Received an update from plugin. extracting info from payload Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus play Sep 06 20:00:26 rpi3bplus volumio[794]: info: Received an update from plugin. extracting info from payload Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus kernel: hwmon hwmon1: Undervoltage detected! Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 389ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 363ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: ------------------------------ 361ms Sep 06 20:00:26 rpi3bplus volumio[794]: info: Pushing Favourites {"favourite":false} Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 205 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 205 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 200 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: info: sendMpdCommand playlistinfo took 200 milliseconds Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: ControllerMpd::parseTrackInfo Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus play Sep 06 20:00:26 rpi3bplus volumio[794]: info: Received an update from plugin. extracting info from payload Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus play Sep 06 20:00:26 rpi3bplus volumio[794]: info: Received an update from plugin. extracting info from payload Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: ControllerMpd::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::servicePushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CorePlayQueue::getTrack 0 Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Get Over It","artist":"Eagles","album":"Hell Freezes Over","uri":"https://v.h.rexzhang.com:55555/Audio/5d07962c927dcf5022b4068d8fbc0f33/stream.mp3?static=true&mediaSourceId=5d07962c927dcf5022b4068d8fbc0f33&tag=8d52a2018b5ceec8c39b44002040f9eb&t.mp3","trackType":"mp3"} Sep 06 20:00:26 rpi3bplus volumio[794]: verbose: CURRENT POSITION 0 Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState stateService play Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::syncState currentStatus play Sep 06 20:00:26 rpi3bplus volumio[794]: info: Received an update from plugin. extracting info from payload Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:26 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:27 rpi3bplus volumio[794]: info: ------------------------------ 550ms Sep 06 20:00:27 rpi3bplus volumio[794]: info: ------------------------------ 376ms Sep 06 20:00:27 rpi3bplus volumio[794]: info: ------------------------------ 373ms Sep 06 20:00:27 rpi3bplus volumio[794]: info: Pushing Favourites {"favourite":false} Sep 06 20:00:27 rpi3bplus volumio[794]: info: [jellyfin-play]: Reported 'start' for song: Get Over It (at 0 ms) Sep 06 20:00:27 rpi3bplus volumio[794]: info: [jellyfin-play]: Reported 'timeupdate' for song: Get Over It (at 114 ms) Sep 06 20:00:27 rpi3bplus volumio[794]: info: [jellyfin-play]: Reported 'timeupdate' for song: Get Over It (at 982 ms) Sep 06 20:00:30 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 20:00:30 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 20:00:30 rpi3bplus volumio[794]: info: Discovery: Getting this device information Sep 06 20:00:30 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioGetState Sep 06 20:00:30 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 20:00:30 rpi3bplus kernel: hwmon hwmon1: Voltage normalised Sep 06 20:00:37 rpi3bplus kernel: hwmon hwmon1: Undervoltage detected! Sep 06 20:00:41 rpi3bplus kernel: hwmon hwmon1: Voltage normalised Sep 06 20:00:51 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume22 Sep 06 20:00:51 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:51 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:51 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:51 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume24 Sep 06 20:00:51 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:51 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:51 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:52 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume38 Sep 06 20:00:52 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:52 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:52 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:52 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume43 Sep 06 20:00:52 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:52 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:52 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:53 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume36 Sep 06 20:00:53 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:53 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:53 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:54 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume44 Sep 06 20:00:54 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:54 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:54 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:56 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume32 Sep 06 20:00:56 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:56 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:56 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:57 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume34 Sep 06 20:00:57 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:57 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:57 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:00:59 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume25 Sep 06 20:00:59 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:00:59 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:00:59 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:00 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 20:01:00 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 20:01:00 rpi3bplus volumio[794]: info: Discovery: Getting this device information Sep 06 20:01:00 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioGetState Sep 06 20:01:00 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 20:01:01 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume36 Sep 06 20:01:01 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:01 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:01 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:02 rpi3bplus kernel: hwmon hwmon1: Undervoltage detected! Sep 06 20:01:03 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:03 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:03 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:03 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:03 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:03 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:03 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:03 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:04 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:04 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:04 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:04 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:04 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:04 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:04 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:04 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:05 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:05 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:05 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:05 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:05 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:06 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:06 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:06 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:06 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:06 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:06 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:06 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:06 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:07 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:07 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:07 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:07 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:07 rpi3bplus volumio[794]: info: VolumeController::SetAlsaVolume+ Sep 06 20:01:07 rpi3bplus volumio[794]: info: CoreStateMachine::pushState Sep 06 20:01:07 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 20:01:07 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioPushState Sep 06 20:01:12 rpi3bplus kernel: hwmon hwmon1: Voltage normalised Sep 06 20:01:30 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 20:01:30 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 20:01:30 rpi3bplus volumio[794]: info: Discovery: Getting this device information Sep 06 20:01:30 rpi3bplus volumio[794]: info: CoreCommandRouter::volumioGetState Sep 06 20:01:30 rpi3bplus volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 20:01:30 rpi3bplus volumio[794]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 20:01:30 rpi3bplus volumio[794]: Error: connect ETIMEDOUT 157.240.0.18:443 Sep 06 20:01:30 rpi3bplus volumio[794]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 06 20:01:30 rpi3bplus volumio[794]: errno: -110, Sep 06 20:01:30 rpi3bplus volumio[794]: code: 'ETIMEDOUT', Sep 06 20:01:30 rpi3bplus volumio[794]: syscall: 'connect', Sep 06 20:01:30 rpi3bplus volumio[794]: address: '157.240.0.18', Sep 06 20:01:30 rpi3bplus volumio[794]: port: 443 Sep 06 20:01:30 rpi3bplus volumio[794]: } Sep 06 20:01:30 rpi3bplus volumio[794]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 20:01:31 rpi3bplus sudo[2442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 20:00 Sep 06 20:01:31 rpi3bplus sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"