-- Logs begin at Fri 2024-09-20 13:44:01 CEST, end at Thu 2024-10-17 19:02:20 CEST. -- Oct 17 19:01:00 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:03 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:04 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:07 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:08 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:09 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Oct 17 19:01:09 volumio volumio[894]: info: Share Synology successfully unmounted Oct 17 19:01:09 volumio sudo[3895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.68.112:volume1/music /mnt/NAS/Synology Oct 17 19:01:09 volumio sudo[3895]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:01:09 volumio sudo[3895]: pam_unix(sudo:session): session closed for user root Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:01:09 volumio volumio[894]: info: Oct 17 19:01:09 volumio volumio[894]: ---------------------------- MPD announces state update: update Oct 17 19:01:09 volumio volumio[894]: info: ControllerMpd::getState Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:01:09 volumio volumio[894]: info: Oct 17 19:01:09 volumio volumio[894]: ---------------------------- MPD announces state update: update Oct 17 19:01:09 volumio volumio[894]: info: ControllerMpd::getState Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:01:09 volumio volumio[894]: info: Oct 17 19:01:09 volumio volumio[894]: ---------------------------- MPD announces state update: update Oct 17 19:01:09 volumio volumio[894]: info: ControllerMpd::getState Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand status took 16 milliseconds Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand status took 12 milliseconds Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand status took 11 milliseconds Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand status took 9 milliseconds Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand status took 9 milliseconds Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand status took 7 milliseconds Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:01:09 volumio volumio[894]: info: Command Router : Notfying DB Updatetrue Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:01:09 volumio volumio[894]: info: Command Router : Notfying DB Updatetrue Oct 17 19:01:09 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:01:09 volumio volumio[894]: info: Command Router : Notfying DB Updatetrue Oct 17 19:01:09 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:01:09 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:01:09 volumio volumio[894]: info: [1729184469983] Database update started Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand playlistinfo took 138 milliseconds Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand playlistinfo took 103 milliseconds Oct 17 19:01:09 volumio volumio[894]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 17 19:01:10 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:01:10 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:01:10 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:01:10 volumio volumio[894]: info: ControllerMpd::pushState Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::servicePushState Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Speak to Me/Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Synology/Pink Floyd - Speak to Me-Breathe.mp3","trackType":"mp3"} Oct 17 19:01:10 volumio volumio[894]: verbose: CURRENT POSITION 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: No code Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: ControllerMpd::pushState Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::servicePushState Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Speak to Me/Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Synology/Pink Floyd - Speak to Me-Breathe.mp3","trackType":"mp3"} Oct 17 19:01:10 volumio volumio[894]: verbose: CURRENT POSITION 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: No code Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: ControllerMpd::pushState Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::servicePushState Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Speak to Me/Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Synology/Pink Floyd - Speak to Me-Breathe.mp3","trackType":"mp3"} Oct 17 19:01:10 volumio volumio[894]: verbose: CURRENT POSITION 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: No code Oct 17 19:01:10 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:01:10 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:01:10 volumio volumio[894]: info: ------------------------------ 863ms Oct 17 19:01:10 volumio volumio[894]: info: ------------------------------ 860ms Oct 17 19:01:10 volumio volumio[894]: info: ------------------------------ 859ms Oct 17 19:01:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 17 19:01:11 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:12 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:15 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:16 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:20 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Oct 17 19:01:20 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:25 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:28 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 19:01:28 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 17 19:01:28 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:32 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:36 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:44 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:48 volumio sudo[3967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 19:01:48 volumio sudo[3967]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:01:48 volumio sudo[3967]: pam_unix(sudo:session): session closed for user root Oct 17 19:01:48 volumio sudo[3970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 19:01:48 volumio sudo[3970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:01:48 volumio sudo[3970]: pam_unix(sudo:session): session closed for user root Oct 17 19:01:48 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:48 volumio volumio[894]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/audiophonicsonoff/volumio/buster/armhf Oct 17 19:01:49 volumio volumio[894]: verbose: New Socket.io Connection to 192.168.68.116 from 192.168.68.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Oct 17 19:01:49 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 19:01:49 volumio volumio[894]: info: Received Get System Info Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 19:01:49 volumio volumio[894]: info: Discovery: Getting this device information Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Oct 17 19:01:49 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 19:01:49 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Oct 17 19:01:49 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:01:49 volumio volumio[894]: info: Listing playlists Oct 17 19:01:50 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 19:01:50 volumio volumio[894]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/audiophonicsonoff/volumio/buster/armhf Oct 17 19:01:50 volumio volumio[894]: info: Folder /tmp/plugins removed Oct 17 19:01:50 volumio volumio[894]: info: Check plugin dependencies Oct 17 19:01:50 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 19:01:50 volumio volumio[894]: info: Checking if plugin already exists Oct 17 19:01:50 volumio volumio[894]: info: Rename folder Oct 17 19:01:50 volumio volumio[894]: info: Folder /tmp/downloaded_plugin.zip removed Oct 17 19:01:51 volumio volumio[894]: info: Move to category Oct 17 19:01:51 volumio volumio[894]: info: Checking if install.sh is present Oct 17 19:01:51 volumio volumio[894]: info: Executing install.sh Oct 17 19:01:51 volumio sudo[3987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/audiophonicsonoff/install.sh Oct 17 19:01:51 volumio sudo[3987]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:01:51 volumio volumio[894]: info: Installing Audiophonics on/off and its dependencies... Oct 17 19:01:51 volumio sudo[3987]: pam_unix(sudo:session): session closed for user root Oct 17 19:01:51 volumio volumio[894]: info: Install script completed Oct 17 19:01:51 volumio volumio[894]: info: Adding reference to registry Oct 17 19:01:51 volumio volumio[894]: info: Done installing plugin. Oct 17 19:01:51 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 19:01:51 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 17 19:01:51 volumio volumio[894]: info: Folder /tmp/plugins removed Oct 17 19:01:51 volumio volumio[894]: info: Folder /tmp/downloaded_plugin.zip removed Oct 17 19:01:52 volumio volumio[894]: info: No packages needed, only node_modules are required Oct 17 19:01:52 volumio volumio[894]: Plugin install end detected on script Oct 17 19:01:52 volumio volumio[894]: info: Folder /data/temp removed Oct 17 19:01:52 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:01:54 volumio volumio[894]: info: Enabling plugin audiophonicsonoff Oct 17 19:01:54 volumio volumio[894]: info: Loading plugin "audiophonicsonoff"... Oct 17 19:01:54 volumio volumio[894]: info: Audiophonics on/off initiated Oct 17 19:01:54 volumio volumio[894]: info: PLUGIN START: audiophonicsonoff Oct 17 19:01:54 volumio volumio[894]: info: Configuring GPIO pins Oct 17 19:01:54 volumio volumio[894]: info: Error: Error: EBUSY: resource busy or locked, write Oct 17 19:01:56 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:02:00 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:02:04 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:02:06 volumio volumio[894]: info: Enabling plugin audiophonicsonoff Oct 17 19:02:06 volumio volumio[894]: info: Loading plugin "audiophonicsonoff"... Oct 17 19:02:06 volumio volumio[894]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 17 19:02:06 volumio volumio[894]: info: Audiophonics on/off initiated Oct 17 19:02:06 volumio volumio[894]: info: PLUGIN START: audiophonicsonoff Oct 17 19:02:06 volumio volumio[894]: info: Configuring GPIO pins Oct 17 19:02:06 volumio volumio[894]: info: Error: Error: EBUSY: resource busy or locked, write Oct 17 19:02:07 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 19:02:07 volumio volumio[894]: info: Loaded the previous config. Oct 17 19:02:07 volumio volumio[894]: info: ## populating UI... Oct 17 19:02:07 volumio volumio[894]: info: 1/1 settings loaded Oct 17 19:02:07 volumio volumio[894]: info: Populated config screen. Oct 17 19:02:08 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:02:09 volumio volumio[894]: info: CALLMETHOD: system_controller audiophonicsonoff updateButtonConfig [object Object] Oct 17 19:02:09 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: audiophonicsonoff , updateButtonConfig Oct 17 19:02:12 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Oct 17 19:02:12 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:12 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:02:13 volumio volumio[894]: info: Oct 17 19:02:13 volumio volumio[894]: ---------------------------- MPD announces state update: update Oct 17 19:02:13 volumio volumio[894]: info: ControllerMpd::getState Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:02:13 volumio volumio[894]: info: Oct 17 19:02:13 volumio volumio[894]: ---------------------------- MPD announces state update: update Oct 17 19:02:13 volumio volumio[894]: info: ControllerMpd::getState Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:02:13 volumio volumio[894]: info: Oct 17 19:02:13 volumio volumio[894]: ---------------------------- MPD announces state update: update Oct 17 19:02:13 volumio volumio[894]: info: ControllerMpd::getState Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand status took 8 milliseconds Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand status took 7 milliseconds Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand status took 6 milliseconds Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand status took 6 milliseconds Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand status took 5 milliseconds Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:02:13 volumio volumio[894]: info: Command Router : Notfying DB Updatefalse Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:02:13 volumio volumio[894]: info: Command Router : Notfying DB Updatefalse Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:02:13 volumio volumio[894]: info: Command Router : Notfying DB Updatefalse Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseState Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand playlistinfo took 216 milliseconds Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 17 19:02:13 volumio volumio[894]: info: sendMpdCommand playlistinfo took 58 milliseconds Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:02:13 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:02:13 volumio volumio[894]: info: ControllerMpd::pushState Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::servicePushState Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Speak to Me/Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Synology/Pink Floyd - Speak to Me-Breathe.mp3","trackType":"mp3"} Oct 17 19:02:13 volumio volumio[894]: verbose: CURRENT POSITION 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: No code Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: ControllerMpd::pushState Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::servicePushState Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Speak to Me/Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Synology/Pink Floyd - Speak to Me-Breathe.mp3","trackType":"mp3"} Oct 17 19:02:13 volumio volumio[894]: verbose: CURRENT POSITION 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: No code Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: ControllerMpd::pushState Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::servicePushState Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Speak to Me/Breathe","artist":"Pink Floyd","album":"The Dark Side of the Moon","uri":"NAS/Synology/Pink Floyd - Speak to Me-Breathe.mp3","trackType":"mp3"} Oct 17 19:02:13 volumio volumio[894]: verbose: CURRENT POSITION 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: No code Oct 17 19:02:13 volumio volumio[894]: info: CoreStateMachine::pushState Oct 17 19:02:13 volumio volumio[894]: info: CorePlayQueue::getTrack 55 Oct 17 19:02:13 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Oct 17 19:02:13 volumio volumio[894]: info: ------------------------------ 630ms Oct 17 19:02:13 volumio volumio[894]: info: ------------------------------ 631ms Oct 17 19:02:13 volumio volumio[894]: info: ------------------------------ 630ms Oct 17 19:02:16 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:02:19 volumio volumio[894]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 17 19:02:19 volumio volumio[894]: info: PLUGIN onReboot : networkfs Oct 17 19:02:19 volumio sudo[4044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Synology Oct 17 19:02:19 volumio sudo[4044]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:02:19 volumio systemd[1]: mnt-NAS-Synology.mount: Succeeded. Oct 17 19:02:19 volumio sudo[4044]: pam_unix(sudo:session): session closed for user root Oct 17 19:02:19 volumio volumio[894]: info: PLUGIN onReboot : touch_display Oct 17 19:02:19 volumio sudo[4050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 17 19:02:19 volumio sudo[4050]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:02:19 volumio volumio[894]: info: PLUGIN onReboot : audiophonicsonoff Oct 17 19:02:19 volumio sudo[4050]: pam_unix(sudo:session): session closed for user root Oct 17 19:02:19 volumio volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:02:19 volumio volumio[894]: TypeError: Cannot read property 'writeSync' of undefined Oct 17 19:02:19 volumio volumio[894]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 17 19:02:19 volumio volumio[894]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 17 19:02:19 volumio volumio[894]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 17 19:02:19 volumio volumio[894]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 17 19:02:19 volumio volumio[894]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 17 19:02:19 volumio volumio[894]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 17 19:02:19 volumio volumio[894]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 17 19:02:19 volumio volumio[894]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 17 19:02:19 volumio volumio[894]: at Socket.emit (events.js:315:20) Oct 17 19:02:19 volumio volumio[894]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 17 19:02:19 volumio volumio[894]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 17 19:02:19 volumio volumio[894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:02:19 volumio sudo[4053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Oct 17 19:02:19 volumio sudo[4053]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:02:19 volumio startx[1236]: xinit: connection to X server lost Oct 17 19:02:19 volumio startx[1236]: [1B blob data] Oct 17 19:02:19 volumio systemd[1]: Stopping Volumio Kiosk... Oct 17 19:02:19 volumio startx[1236]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Oct 17 19:02:20 volumio sudo[4072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 19:01 Oct 17 19:02:20 volumio sudo[4072]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:02:20 volumio startx[1236]: xinit: unexpected signal 15 Oct 17 19:02:20 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:02:20 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Oct 17 19:02:20 volumio systemd[1]: Stopped Volumio Kiosk. Oct 17 19:02:20 volumio sudo[4053]: pam_unix(sudo:session): session closed for user root 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"