-- Logs begin at Fri 2024-11-22 21:48:57 WET, end at Sat 2024-11-23 19:03:18 WET. --
Nov 23 19:02:00 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:00 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:00 volumio volumio[27372]: info: Listing playlists
Nov 23 19:02:00 volumio volumio[27372]: info: Listing playlists
Nov 23 19:02:10 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:10 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:20 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:20 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:20 volumio volumio[27372]: info: Listing playlists
Nov 23 19:02:20 volumio volumio[27372]: info: Listing playlists
Nov 23 19:02:30 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:30 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:41 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:41 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:41 volumio volumio[27372]: info: Listing playlists
Nov 23 19:02:41 volumio volumio[27372]: info: Listing playlists
Nov 23 19:02:49 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 23 19:02:54 volumio volumio[27372]: info: Discovery: Getting this device information
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:54 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 23 19:02:54 volumio volumio[27372]: info: Discovery: Getting this device information
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:54 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 23 19:02:54 volumio volumio[27372]: verbose: New Socket.io Connection to 192.168.68.112:3000 from 192.168.68.115 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:54 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 23 19:02:54 volumio volumio[27372]: info: Discovery: Getting this device information
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:54 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 23 19:02:54 volumio volumio[27372]: info: Discovery: Getting this device information
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:54 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:54 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 23 19:02:56 volumio sudo[30503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Nov 23 19:02:56 volumio sudo[30503]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:02:56 volumio sudo[30503]: pam_unix(sudo:session): session closed for user root
Nov 23 19:02:56 volumio sudo[30506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 23 19:02:56 volumio sudo[30506]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:02:56 volumio sudo[30506]: pam_unix(sudo:session): session closed for user root
Nov 23 19:02:56 volumio volumio[27372]: verbose: New Socket.io Connection to 192.168.68.112 from 192.168.68.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Nov 23 19:02:57 volumio sudo[30512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Nov 23 19:02:57 volumio sudo[30512]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:02:57 volumio sudo[30512]: pam_unix(sudo:session): session closed for user root
Nov 23 19:02:57 volumio sudo[30515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 23 19:02:57 volumio sudo[30515]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:02:57 volumio sudo[30515]: pam_unix(sudo:session): session closed for user root
Nov 23 19:02:57 volumio volumio[27372]: verbose: New Socket.io Connection to 192.168.68.112 from 192.168.68.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9
Nov 23 19:02:57 volumio volumio[27372]: info: Executing endpoint metavolumio
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::volumioGetVisibleSources
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:57 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::volumioGetQueue
Nov 23 19:02:57 volumio volumio[27372]: info: CoreStateMachine::getQueue
Nov 23 19:02:57 volumio volumio[27372]: info: CorePlayQueue::getQueue
Nov 23 19:02:57 volumio volumio[27372]: info: Listing playlists
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Nov 23 19:02:57 volumio volumio[27372]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Nov 23 19:02:57 volumio volumio[27372]: info: Received Get System Info
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 23 19:02:57 volumio volumio[27372]: info: Discovery: Getting this device information
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:57 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:57 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Nov 23 19:02:57 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Nov 23 19:02:59 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Nov 23 19:02:59 volumio volumio[27372]: info: Received Get System Info
Nov 23 19:02:59 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 23 19:02:59 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 23 19:02:59 volumio volumio[27372]: info: Discovery: Getting this device information
Nov 23 19:02:59 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:59 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:59 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 23 19:02:59 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Nov 23 19:02:59 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:02:59 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:02:59 volumio volumio[27372]: info: Listing playlists
Nov 23 19:02:59 volumio volumio[27372]: info: Listing playlists
Nov 23 19:03:00 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Nov 23 19:03:00 volumio volumio[27372]: info: Received Get System Info
Nov 23 19:03:00 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 23 19:03:00 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 23 19:03:00 volumio volumio[27372]: info: Discovery: Getting this device information
Nov 23 19:03:00 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:03:00 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:00 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 23 19:03:02 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:03:02 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:04 volumio volumio[27372]: info: Executing endpoint metavolumio
Nov 23 19:03:04 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Nov 23 19:03:04 volumio volumio[27372]: info: Executing endpoint metavolumio
Nov 23 19:03:04 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Nov 23 19:03:04 volumio volumio[27372]: info: Executing endpoint metavolumio
Nov 23 19:03:04 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Nov 23 19:03:04 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Nov 23 19:03:04 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Nov 23 19:03:07 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Nov 23 19:03:09 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:03:09 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:12 volumio volumio[27372]: info: [ASDebug] Port: 24
Nov 23 19:03:12 volumio volumio[27372]: info: [ASDebug] Inverted: false
Nov 23 19:03:12 volumio volumio[27372]: info: [ASDebug] Delay: 720
Nov 23 19:03:15 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Nov 23 19:03:15 volumio volumio[27372]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Nov 23 19:03:15 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Nov 23 19:03:15 volumio volumio[27372]: info: Received Get System Version
Nov 23 19:03:15 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 23 19:03:15 volumio volumio[27372]: info: Received Get System Info
Nov 23 19:03:15 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 23 19:03:15 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 23 19:03:15 volumio volumio[27372]: info: Discovery: Getting this device information
Nov 23 19:03:15 volumio volumio[27372]: info: CoreCommandRouter::volumioGetState
Nov 23 19:03:15 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:15 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 23 19:03:17 volumio volumio[27372]: info: Stopping peppyspectrum service
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::stop
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::stPlaybackTimer
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::updateTrackBlock
Nov 23 19:03:17 volumio volumio[27372]: info: CorePlayQueue::getTrackBlock
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::pushState
Nov 23 19:03:17 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::volumioPushState
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Pushing multiroomSync output update for this device
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Pushing multiroomSync output
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::serviceStop
Nov 23 19:03:17 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::serviceStop
Nov 23 19:03:17 volumio volumio[27372]: info: ControllerMpd::stop
Nov 23 19:03:17 volumio volumio[27372]: verbose: ControllerMpd::sendMpdCommand stop
Nov 23 19:03:17 volumio volumio[27372]: info: Disabling plugin peppyspectrum
Nov 23 19:03:17 volumio volumio[27372]: info: Preparing to generate the ALSA configuration file
Nov 23 19:03:17 volumio volumio[27372]: info: [ASDebug] CurState: stop PrevState: play
Nov 23 19:03:17 volumio volumio[27372]: info: [ASDebug] InitTimeout - Amp off in: 720 ms
Nov 23 19:03:17 volumio volumio[27372]: info: PeppySpectrum ---peppyspectrum status stop
Nov 23 19:03:17 volumio volumio[27372]: info:
Nov 23 19:03:17 volumio volumio[27372]: ---------------------------- MPD announces state update: player
Nov 23 19:03:17 volumio volumio[27372]: info: sendMpdCommand stop took 56 milliseconds
Nov 23 19:03:17 volumio volumio[27372]: info: ControllerMpd::getState
Nov 23 19:03:17 volumio volumio[27372]: verbose: ControllerMpd::sendMpdCommand status
Nov 23 19:03:17 volumio sudo[30584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service
Nov 23 19:03:17 volumio sudo[30584]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:17 volumio sudo[30584]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:17 volumio volumio[27372]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Nov 23 19:03:17 volumio volumio[27372]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Nov 23 19:03:17 volumio volumio[27372]: info: Reading ALSA contributions from plugins.
Nov 23 19:03:17 volumio volumio[27372]: info: sendMpdCommand status took 33 milliseconds
Nov 23 19:03:17 volumio volumio[27372]: verbose: ControllerMpd::parseState
Nov 23 19:03:17 volumio volumio[27372]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 23 19:03:17 volumio volumio[27372]: info: peppyspectrum Daemon Stop
Nov 23 19:03:17 volumio volumio[27372]: info: sendMpdCommand playlistinfo took 3 milliseconds
Nov 23 19:03:17 volumio volumio[27372]: verbose: ControllerMpd::parseTrackInfo
Nov 23 19:03:17 volumio volumio[27372]: info: ControllerMpd::pushState
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::servicePushState
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::pushState
Nov 23 19:03:17 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::volumioPushState
Nov 23 19:03:17 volumio sudo[30587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Pushing multiroomSync output update for this device
Nov 23 19:03:17 volumio sudo[30587]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Pushing multiroomSync output
Nov 23 19:03:17 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:17 volumio volumio[27372]: 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":"Up On Cripple Creek","artist":"Bob Dylan & The Band","album":"Before The Flood","uri":"NAS/Music_HR/Bob Dylan/Bob Dylan And The Band - Before The Flood (1974) [2014 MFSL SACD 24-192 FLAC] [88]/07 -Up On Cripple Creek.flac","trackType":"flac"}
Nov 23 19:03:17 volumio volumio[27372]: verbose: CURRENT POSITION 6
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::syncState stateService stop
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::syncState currentStatus stop
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::pushState
Nov 23 19:03:17 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::volumioPushState
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Pushing multiroomSync output update for this device
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Pushing multiroomSync output
Nov 23 19:03:17 volumio volumio[27372]: info: No code
Nov 23 19:03:17 volumio volumio[27372]: info: CoreStateMachine::pushState
Nov 23 19:03:17 volumio volumio[27372]: info: CorePlayQueue::getTrack 6
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::volumioPushState
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Pushing multiroomSync output update for this device
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Pushing multiroomSync output
Nov 23 19:03:17 volumio volumio[27372]: info: ------------------------------ 54ms
Nov 23 19:03:17 volumio sudo[30587]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:17 volumio volumio[27372]: info: [ASDebug] CurState: stop PrevState: play
Nov 23 19:03:17 volumio volumio[27372]: info: [ASDebug] InitTimeout - Amp off in: 720 ms
Nov 23 19:03:17 volumio volumio[27372]: info: [ASDebug] CurState: stop PrevState: play
Nov 23 19:03:17 volumio volumio[27372]: info: [ASDebug] InitTimeout - Amp off in: 720 ms
Nov 23 19:03:17 volumio volumio[27372]: info: [ASDebug] CurState: stop PrevState: play
Nov 23 19:03:17 volumio volumio[27372]: info: [ASDebug] InitTimeout - Amp off in: 720 ms
Nov 23 19:03:17 volumio volumio[27372]: info: Asound.conf file written
Nov 23 19:03:17 volumio sudo[30590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Nov 23 19:03:17 volumio sudo[30590]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:17 volumio sudo[30590]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:17 volumio volumio[27372]: xcb_connection_has_error() returned true
Nov 23 19:03:17 volumio volumio[27372]: info: Output device has changed, restarting MPD
Nov 23 19:03:17 volumio sudo[30595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Nov 23 19:03:17 volumio sudo[30595]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:17 volumio sudo[30595]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:17 volumio volumio[27372]: info: Output device has changed, restarting Shairport Sync
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 23 19:03:17 volumio sudo[30598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Nov 23 19:03:17 volumio sudo[30598]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:17 volumio systemd[1]: Stopping Music Player Daemon...
Nov 23 19:03:17 volumio systemd[1]: mpd.service: Succeeded.
Nov 23 19:03:17 volumio systemd[1]: Stopped Music Player Daemon.
Nov 23 19:03:17 volumio volumio[27372]: xcb_connection_has_error() returned true
Nov 23 19:03:17 volumio volumio[27372]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Nov 23 19:03:17 volumio volumio[27372]: xcb_connection_has_error() returned true
Nov 23 19:03:17 volumio volumio[27372]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Nov 23 19:03:17 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 23 19:03:18 volumio vtcs[14495]: [2024-11-23 19:03:18.010] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected
Nov 23 19:03:18 volumio vtcs[14495]: [2024-11-23 19:03:18.011] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE
Nov 23 19:03:18 volumio systemd[1]: Starting Music Player Daemon...
Nov 23 19:03:18 volumio sudo[30607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Nov 23 19:03:18 volumio volumio[27372]: info: Done.
Nov 23 19:03:18 volumio volumio[27372]: ------------------------------------ BT MESSAGE: BT STATUS: running
Nov 23 19:03:18 volumio volumio[27372]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Nov 23 19:03:18 volumio sudo[30607]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:18 volumio volumio[27372]: info: MPD Permissions set
Nov 23 19:03:18 volumio systemd[1]: Stopping Volumio Tidal Connect Service...
Nov 23 19:03:18 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Nov 23 19:03:18 volumio systemd[1]: vtcs.service: Succeeded.
Nov 23 19:03:18 volumio systemd[1]: Stopped Volumio Tidal Connect Service.
Nov 23 19:03:18 volumio volumio[27372]: ------------------------------------ BT MESSAGE: BT STATUS: running
Nov 23 19:03:18 volumio volumio[27372]: ------------------------------------ BT MESSAGE: BT STATUS: running
Nov 23 19:03:18 volumio sudo[30614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Nov 23 19:03:18 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 23 19:03:18 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 23 19:03:18 volumio sudo[30607]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:18 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 23 19:03:18 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 23 19:03:18 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 23 19:03:18 volumio sudo[30614]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:18 volumio sudo[30614]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:18 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 23 19:03:18 volumio volumio[27372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 23 19:03:18 volumio volumio[27372]: info: Starting Shairport Sync
Nov 23 19:03:18 volumio sudo[30603]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Nov 23 19:03:18 volumio sudo[30603]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:18 volumio sudo[30603]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:18 volumio sudo[30624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Nov 23 19:03:18 volumio sudo[30624]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 23 19:03:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Nov 23 19:03:18 volumio systemd[1]: shairport-sync.service: Succeeded.
Nov 23 19:03:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Nov 23 19:03:18 volumio volumio[27372]: ------------------------------------ BT MESSAGE: BT STATUS: running
Nov 23 19:03:18 volumio volumio[27372]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Nov 23 19:03:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Nov 23 19:03:18 volumio sudo[30624]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:18 volumio volumio[27372]: info: Shairport-Sync Started
Nov 23 19:03:18 volumio volumio[27372]: info: [ASDebug] Togle GPIO: OFF
Nov 23 19:03:18 volumio volumio[27372]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Nov 23 19:03:18 volumio volumio[27372]: Error: EBADF: bad file descriptor, write
Nov 23 19:03:18 volumio volumio[27372]: at Object.writeSync (fs.js:711:3)
Nov 23 19:03:18 volumio volumio[27372]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8)
Nov 23 19:03:18 volumio volumio[27372]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23)
Nov 23 19:03:18 volumio volumio[27372]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88)
Nov 23 19:03:18 volumio volumio[27372]: at listOnTimeout (internal/timers.js:554:17)
Nov 23 19:03:18 volumio volumio[27372]: at processTimers (internal/timers.js:497:7) {
Nov 23 19:03:18 volumio volumio[27372]: errno: -9,
Nov 23 19:03:18 volumio volumio[27372]: syscall: 'write',
Nov 23 19:03:18 volumio volumio[27372]: code: 'EBADF'
Nov 23 19:03:18 volumio volumio[27372]: }
Nov 23 19:03:18 volumio volumio[27372]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Nov 23 19:03:18 volumio mpd[30625]: Nov 23 19:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Nov 23 19:03:18 volumio systemd[1]: Started Music Player Daemon.
Nov 23 19:03:18 volumio sudo[30598]: pam_unix(sudo:session): session closed for user root
Nov 23 19:03:18 volumio sudo[30641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 19:02
Nov 23 19:03:18 volumio sudo[30641]: 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"