-- Logs begin at Fri 2024-07-19 13:12:58 UTC, end at Fri 2024-07-19 13:15:14 UTC. --
Jul 19 13:14:00 volumio volumio[749]: info: Checking against Known Cards name
Jul 19 13:14:00 volumio volumio[749]: info: Adding Server instance for streaming
Jul 19 13:14:00 volumio volumio[749]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Jul 19 13:14:00 volumio volumio[749]: error: Hi Res Audio Failed Login: Missing Login Data
Jul 19 13:14:00 volumio volumio[749]: info: Adding HIGHRESAUDIO REST API Endpoints
Jul 19 13:14:00 volumio volumio[749]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Jul 19 13:14:00 volumio volumio[749]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Jul 19 13:14:00 volumio volumio[749]: info: Refreshing TIDAL token
Jul 19 13:14:00 volumio volumio[749]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Jul 19 13:14:00 volumio volumio[749]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Jul 19 13:14:00 volumio sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Jul 19 13:14:00 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:14:00 volumio volumio[749]: info: Stopping AccessToken refresher cron for QOBUZ
Jul 19 13:14:00 volumio volumio[749]: info: AccessToken refresher cron started for QOBUZ
Jul 19 13:14:00 volumio volumio[749]: info: Adding QOBUZ REST API Endpoints
Jul 19 13:14:00 volumio volumio[749]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Jul 19 13:14:00 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root
Jul 19 13:14:00 volumio volumio[749]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Jul 19 13:14:00 volumio volumio[749]: info: Bluetooth name changed to Volumio
Jul 19 13:14:00 volumio volumio[749]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Jul 19 13:14:00 volumio volumio[749]: info: MPD Permissions set
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:00 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:00 volumio volumio[749]: info: Starting Shairport Sync
Jul 19 13:14:00 volumio mpd[1442]: Jul 19 13:14 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jul 19 13:14:00 volumio mpd[1442]: Jul 19 13:14 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Jul 19 13:14:00 volumio mpd[1442]: Jul 19 13:14 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Jul 19 13:14:00 volumio systemd[1]: Started Music Player Daemon.
Jul 19 13:14:00 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set...
Jul 19 13:14:00 volumio sudo[1429]: pam_unix(sudo:session): session closed for user root
Jul 19 13:14:00 volumio volumio[749]: error: updateQueue error: null
Jul 19 13:14:00 volumio sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 19 13:14:00 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:14:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jul 19 13:14:00 volumio systemd[1]: shairport-sync.service: Succeeded.
Jul 19 13:14:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jul 19 13:14:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jul 19 13:14:00 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root
Jul 19 13:14:00 volumio volumio[749]: info: Shairport-Sync Started
Jul 19 13:14:00 volumio volumio[749]: info: Successfully Updated MyVolumio device
Jul 19 13:14:00 volumio volumio[749]: info: Successfully Updated MyVolumio device
Jul 19 13:14:00 volumio volumio[749]: error: No access token received for TIDAL
Jul 19 13:14:00 volumio volumio[1457]: cset: --> shielding system active with
Jul 19 13:14:00 volumio volumio[1457]: cset: "system" cpuset of CPUSPEC(1-3) with 178 tasks running
Jul 19 13:14:00 volumio volumio[1457]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running
Jul 19 13:14:00 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set.
Jul 19 13:14:01 volumio sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Jul 19 13:14:01 volumio sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:14:01 volumio systemd[1]: Started MyVolumio SSH Tunnel.
Jul 19 13:14:01 volumio sudo[1483]: pam_unix(sudo:session): session closed for user root
Jul 19 13:14:01 volumio volumio[749]: info: Remote SSH Started
Jul 19 13:14:01 volumio autossh[1486]: port set to 0, monitoring disabled
Jul 19 13:14:01 volumio autossh[1486]: starting ssh (count 1)
Jul 19 13:14:01 volumio autossh[1486]: ssh child pid is 1489
Jul 19 13:14:01 volumio volumio[749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15
Jul 19 13:14:01 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:01 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:01 volumio volumiossh-tunnel[1485]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts.
Jul 19 13:14:02 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Jul 19 13:14:02 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:14:02 volumio systemd[1]: Started Volumio Bluetooth Module.
Jul 19 13:14:02 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root
Jul 19 13:14:02 volumio volumio[749]: info: Volumio BT Module successfully started
Jul 19 13:14:02 volumio sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/*
Jul 19 13:14:02 volumio sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:14:02 volumio volumiobt[1493]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory
Jul 19 13:14:02 volumio sudo[1494]: pam_unix(sudo:session): session closed for user root
Jul 19 13:14:03 volumio volumio[749]: info: TidalConnect service stoped!
Jul 19 13:14:03 volumio volumio[749]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Jul 19 13:14:03 volumio volumio[749]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Jul 19 13:14:03 volumio sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Jul 19 13:14:03 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:14:03 volumio systemd[1]: Started Volumio Tidal Connect Service.
Jul 19 13:14:03 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root
Jul 19 13:14:03 volumio volumio[749]: info: Executing endpoint tc_getconfig
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Jul 19 13:14:03 volumio vtcs[1508]: STARTING TidalConnect services, version: 1.1.7.5
Jul 19 13:14:03 volumio vtcs[1508]: STARTED TidalConnect services.
Jul 19 13:14:03 volumio volumio[749]: info: Executing endpoint tc_connect
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Jul 19 13:14:03 volumio volumio[749]: info: Connecting to TidalConnect
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:03 volumio volumio[749]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Jul 19 13:14:03 volumio volumio[749]: verbose: CURRENT POSITION 0
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:03 volumio volumio[749]: info: No code
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:03 volumio volumio[749]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Jul 19 13:14:03 volumio volumio[749]: verbose: CURRENT POSITION 0
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:03 volumio volumio[749]: info: No code
Jul 19 13:14:03 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:03 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:03 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:03 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:03 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:03 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:03 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:03 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:03 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:03 volumio sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio
Jul 19 13:14:03 volumio sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:14:03 volumio volumiobt[1493]: pulseaudio: no process found
Jul 19 13:14:03 volumio sudo[1526]: pam_unix(sudo:session): session closed for user root
Jul 19 13:14:03 volumio dbus-daemon[504]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.19' (uid=1000 pid=1530 comm="/usr/bin/pulseaudio --high-priority=true ")
Jul 19 13:14:03 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Jul 19 13:14:03 volumio volumio[749]: ------------------------------------ BT MESSAGE: BT STATUS: running
Jul 19 13:14:03 volumio dbus-daemon[504]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Jul 19 13:14:03 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service.
Jul 19 13:14:03 volumio rtkit-daemon[1531]: Successfully called chroot.
Jul 19 13:14:03 volumio rtkit-daemon[1531]: Successfully dropped privileges.
Jul 19 13:14:03 volumio rtkit-daemon[1531]: Successfully limited resources.
Jul 19 13:14:03 volumio rtkit-daemon[1531]: Running.
Jul 19 13:14:03 volumio rtkit-daemon[1531]: Canary thread running.
Jul 19 13:14:03 volumio rtkit-daemon[1531]: Watchdog thread running.
Jul 19 13:14:03 volumio dbus-daemon[504]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.20' (uid=0 pid=1531 comm="/usr/lib/rtkit/rtkit-daemon ")
Jul 19 13:14:03 volumio systemd[1]: Starting Authorization Manager...
Jul 19 13:14:03 volumio volumio[749]: ------------------------------------ BT MESSAGE: BT STATUS: running
Jul 19 13:14:03 volumio polkitd[1534]: started daemon version 0.105 using authority implementation `local' version `0.105'
Jul 19 13:14:03 volumio dbus-daemon[504]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Jul 19 13:14:03 volumio systemd[1]: Started Authorization Manager.
Jul 19 13:14:03 volumio volumio[749]: info: [yt-cast-receiver] Connecting sender through DIAL...
Jul 19 13:14:04 volumio pulseaudio[1530]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Jul 19 13:14:04 volumio pulseaudio[1530]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Jul 19 13:14:04 volumio bluetoothd[774]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSource
Jul 19 13:14:04 volumio bluetoothd[774]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSink
Jul 19 13:14:04 volumio volumiobt[1493]: Applying permissions
Jul 19 13:14:04 volumio volumiobt[1493]: Setting BT discoverable and pairable
Jul 19 13:14:04 volumio pulseaudio[1530]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
Jul 19 13:14:04 volumio kernel: Bluetooth: RFCOMM TTY layer initialized
Jul 19 13:14:04 volumio kernel: Bluetooth: RFCOMM socket layer initialized
Jul 19 13:14:04 volumio kernel: Bluetooth: RFCOMM ver 1.11
Jul 19 13:14:04 volumio volumiobt[1493]: Agent registered
Jul 19 13:14:04 volumio volumiobt[1493]: [bluetooth]# -e power on
Jul 19 13:14:04 volumio volumiobt[1493]: Invalid command in menu main: -e
Jul 19 13:14:04 volumio volumiobt[1493]:
Jul 19 13:14:04 volumio volumiobt[1493]: Use "help" for a list of available commands in a menu.
Jul 19 13:14:04 volumio volumiobt[1493]: Use "menu " if you want to enter any submenu.
Jul 19 13:14:04 volumio volumiobt[1493]: Use "back" if you want to return to menu main.
Jul 19 13:14:04 volumio volumiobt[1493]: [bluetooth]# agent on
Jul 19 13:14:04 volumio volumiobt[1493]: Agent is already registered
Jul 19 13:14:04 volumio volumiobt[1493]: [bluetooth]# discoverable on
Jul 19 13:14:04 volumio volumiobt[1493]: [bluetooth]# pairable on
Jul 19 13:14:04 volumio volumiobt[1493]: [bluetooth]# agent NoInputNoOutput
Jul 19 13:14:04 volumio volumiobt[1493]: Agent is already registered
Jul 19 13:14:04 volumio volumiobt[1493]: [bluetooth]# default-agent
Jul 19 13:14:04 volumio volumiobt[1493]: [bluetooth]# quit
Jul 19 13:14:04 volumio volumiobt[1493]: [59B blob data]
Jul 19 13:14:04 volumio volumio[749]: info: MRS: Getting audio outputs on start
Jul 19 13:14:04 volumio volumio[749]: info: MRS: Requesting all other devices output
Jul 19 13:14:06 volumio volumio[749]: info: TidalConnect service started!
Jul 19 13:14:06 volumio volumio[749]: info: BOOT COMPLETED
Jul 19 13:14:06 volumio volumio[749]: [Metrics] CommandRouter: 51s 826.95ms
Jul 19 13:14:06 volumio volumio[749]: info: CoreCommandRouter::volumiosetStartupVolume
Jul 19 13:14:06 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:06 volumio volumio[749]: info: VolumeController:: Setting startup Volume 100
Jul 19 13:14:06 volumio volumio[749]: info: VolumeController::SetAlsaVolume100
Jul 19 13:14:06 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 19 13:14:06 volumio volumio[749]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:14:06 volumio volumio[749]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:14:06 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:06 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:06 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:06 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:06 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:06 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:06 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:06 volumio volumio[749]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 19 13:14:06 volumio volumio[749]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 19 13:14:06 volumio volumio[749]: info: camilladsp stopping service pid 1188...
Jul 19 13:14:06 volumio volumio[749]: info: camilladsp service terminated, instance 1
Jul 19 13:14:06 volumio volumio[749]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 19 13:14:06 volumio volumio[749]: info: camilladsp service started and running in background, instance 1
Jul 19 13:14:06 volumio volumio[749]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 19 13:14:06 volumio volumio[749]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 19 13:14:06 volumio volumio[749]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 19 13:14:06 volumio volumio[749]: error: FusionDsp - WebSocket error: [object Object]
Jul 19 13:14:06 volumio volumio[749]: info: camilladsp spawned new process with pid 1551, instance 1, run: true
Jul 19 13:14:06 volumio volumio[749]: info: [yt-cast-receiver] (YouTube Music) Sender connected: Desktop (user: Kolybokas)
Jul 19 13:14:06 volumio volumio[749]: info: [ytcr] ***** Sender connected *****
Jul 19 13:14:06 volumio volumio[749]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:14:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jul 19 13:14:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 19 13:14:07 volumio volumio[749]: xcb_connection_has_error() returned true
Jul 19 13:14:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jul 19 13:14:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 19 13:14:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 19 13:14:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 19 13:14:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 19 13:14:08 volumio volumio[749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 16
Jul 19 13:14:08 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:08 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:08 volumio volumio-remote-updater[500]: No test mode
Jul 19 13:14:08 volumio volumio-remote-updater[500]: No alpha test mode
Jul 19 13:14:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jul 19 13:14:08 volumio volumio[749]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for missing library after reboot with slow USB devices
\n
\nNEW ADDITIONS
\n\n- Improvements for MPD service
\n- Add Album title to playlist, queue and play next
\n
\n","title":"Update v3.703","updateavailable":true}
Jul 19 13:14:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 19 13:14:11 volumio volumio[749]: info: [yt-cast-receiver] Player.resume()
Jul 19 13:14:11 volumio volumio[749]: info: camilladsp spawned new process with pid 1563, instance 1, run: true
Jul 19 13:14:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 19 13:14:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jul 19 13:14:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 19 13:14:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jul 19 13:14:13 volumio volumio[749]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 19 13:14:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 19 13:14:14 volumio ntpd[706]: Soliciting pool server 185.177.150.95
Jul 19 13:14:15 volumio volumio[749]: info: [yt-cast-receiver] Player.play(): H5aYhA5qv7A @ 0s
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:15 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:15 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::volumioStop
Jul 19 13:14:15 volumio volumio[749]: info: CoreStateMachine::stop
Jul 19 13:14:15 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:15 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Jul 19 13:14:15 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Jul 19 13:14:15 volumio ntpd[706]: Soliciting pool server 5.20.0.21
Jul 19 13:14:15 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:15 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:15 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Jul 19 13:14:15 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:15 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:15 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:15 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:15 volumio volumio[512]: register 5
Jul 19 13:14:15 volumio volumio[512]: success
Jul 19 13:14:15 volumio volumio[512]: register 6
Jul 19 13:14:15 volumio volumio[512]: success
Jul 19 13:14:15 volumio volumio[512]: register 16
Jul 19 13:14:15 volumio volumio[512]: success
Jul 19 13:14:15 volumio volumio[512]: register 20
Jul 19 13:14:15 volumio volumio[512]: success
Jul 19 13:14:15 volumio volumio[512]: Traceback (most recent call last):
Jul 19 13:14:15 volumio volumio[512]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 721, in
Jul 19 13:14:15 volumio volumio[512]: main()
Jul 19 13:14:15 volumio volumio[512]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 697, in main
Jul 19 13:14:15 volumio volumio[512]: SOCKETIO.wait()
Jul 19 13:14:15 volumio volumio[512]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 251, in wait
Jul 19 13:14:15 volumio volumio[512]: self._process_packets()
Jul 19 13:14:15 volumio volumio[512]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 278, in _process_packets
Jul 19 13:14:15 volumio volumio[512]: self._process_packet(engineIO_packet)
Jul 19 13:14:15 volumio volumio[512]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 491, in _process_packet
Jul 19 13:14:15 volumio volumio[512]: delegate(parse_socketIO_packet_data(socketIO_packet_data), namespace)
Jul 19 13:14:15 volumio volumio[512]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 513, in _on_event
Jul 19 13:14:15 volumio volumio[512]: namespace._find_packet_callback(event)(*args)
Jul 19 13:14:15 volumio volumio[512]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 368, in on_push_state
Jul 19 13:14:15 volumio volumio[512]: if not args[0]['title'] and not args[0]['artist'] and not args[0]['album'] and LEN_QUEUE > 0:
Jul 19 13:14:15 volumio volumio[512]: KeyError: 'album'
Jul 19 13:14:15 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:15 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:15 volumio volumio[749]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.qKW4aqIGtiT9zvTSPL7sRNPo4JB3.9c60db2dea6960b1a01a54fa6de1e575.state.track'
Jul 19 13:14:15 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 13:14:15 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'.
Jul 19 13:14:15 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart.
Jul 19 13:14:15 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1.
Jul 19 13:14:15 volumio systemd[1]: Stopped Service for using pirate audio display in volumio.
Jul 19 13:14:15 volumio systemd[1]: Started Service for using pirate audio display in volumio.
Jul 19 13:14:16 volumio ntpd[706]: Soliciting pool server 193.219.61.120
Jul 19 13:14:16 volumio ntpd[706]: Soliciting pool server 165.227.160.4
Jul 19 13:14:16 volumio volumio[749]: error: [ytcr] Error in VideoLoader.getInfo(H5aYhA5qv7A):
Jul 19 13:14:16 volumio volumio[749]: (SyntaxError) Unexpected token (1:47)
Jul 19 13:14:16 volumio volumio[749]: SyntaxError: Unexpected token (1:47)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$4.raise (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:3460:15)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$9.unexpected (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:761:10)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$9.semicolon (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:738:68)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$8.parseVarStatement (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:1169:10)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$8.parseStatement (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:916:19)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$8.parseBlock (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:1237:23)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$5.parseFunctionBody (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:3286:24)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$8.parseFunction (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:1359:10)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$8.parseFunctionStatement (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:1059:17)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$8.parseStatement (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:904:19)
Jul 19 13:14:16 volumio volumio[749]: at Parser.pp$8.parseTopLevel (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:818:23)
Jul 19 13:14:16 volumio volumio[749]: at Parser.parse (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:590:17)
Jul 19 13:14:16 volumio volumio[749]: at Function.parse (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:640:37)
Jul 19 13:14:16 volumio volumio[749]: at parse (/data/plugins/music_service/ytcr/node_modules/acorn/dist/acorn.js:5567:19)
Jul 19 13:14:16 volumio volumio[749]: at new Jinter (/data/plugins/music_service/ytcr/node_modules/jintr/bundle/jinter.cjs:1029:44)
Jul 19 13:14:16 volumio volumio[749]: at Object.evaluate [as eval] (/data/plugins/music_service/ytcr/node_modules/volumio-youtubei.js/bundle/node.cjs:21633:19)
Jul 19 13:14:16 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:16 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Jul 19 13:14:16 volumio volumio[749]: info: CoreStateMachine::pushState
Jul 19 13:14:16 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Jul 19 13:14:16 volumio volumio[749]: info: MRS: Pushing multiroomSync output update for this device
Jul 19 13:14:16 volumio volumio[749]: info: MRS: Pushing multiroomSync output
Jul 19 13:14:16 volumio volumio[749]: info: Ignoring MPD Status Update
Jul 19 13:14:16 volumio volumio[749]: info: Ignoring MPD Status Update
Jul 19 13:14:16 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 19 13:14:16 volumio volumio[749]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.qKW4aqIGtiT9zvTSPL7sRNPo4JB3.9c60db2dea6960b1a01a54fa6de1e575.state.track'
Jul 19 13:14:16 volumio volumio[749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Total Clients: 16
Jul 19 13:14:17 volumio ntpd[706]: Soliciting pool server 78.58.99.133
Jul 19 13:14:17 volumio ntpd[706]: Soliciting pool server 5.20.0.20
Jul 19 13:14:17 volumio ntpd[706]: Soliciting pool server 212.59.0.1
Jul 19 13:14:17 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:17 volumio volumio[749]: info: CoreCommandRouter::volumioGetQueue
Jul 19 13:14:17 volumio volumio[749]: info: CoreStateMachine::getQueue
Jul 19 13:14:17 volumio volumio[749]: info: CorePlayQueue::getQueue
Jul 19 13:14:17 volumio volumio[1573]: register 5
Jul 19 13:14:17 volumio volumio[1573]: success
Jul 19 13:14:17 volumio volumio[1573]: register 6
Jul 19 13:14:17 volumio volumio[1573]: success
Jul 19 13:14:17 volumio volumio[1573]: register 16
Jul 19 13:14:17 volumio volumio[1573]: success
Jul 19 13:14:17 volumio volumio[1573]: register 20
Jul 19 13:14:17 volumio volumio[1573]: success
Jul 19 13:14:17 volumio volumio[1573]: Traceback (most recent call last):
Jul 19 13:14:17 volumio volumio[1573]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 721, in
Jul 19 13:14:17 volumio volumio[1573]: main()
Jul 19 13:14:17 volumio volumio[1573]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 697, in main
Jul 19 13:14:17 volumio volumio[1573]: SOCKETIO.wait()
Jul 19 13:14:17 volumio volumio[1573]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 251, in wait
Jul 19 13:14:17 volumio volumio[1573]: self._process_packets()
Jul 19 13:14:17 volumio volumio[1573]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 278, in _process_packets
Jul 19 13:14:17 volumio volumio[1573]: self._process_packet(engineIO_packet)
Jul 19 13:14:17 volumio volumio[1573]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 491, in _process_packet
Jul 19 13:14:17 volumio volumio[1573]: delegate(parse_socketIO_packet_data(socketIO_packet_data), namespace)
Jul 19 13:14:17 volumio volumio[1573]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 513, in _on_event
Jul 19 13:14:17 volumio volumio[1573]: namespace._find_packet_callback(event)(*args)
Jul 19 13:14:17 volumio volumio[1573]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 368, in on_push_state
Jul 19 13:14:17 volumio volumio[1573]: if not args[0]['title'] and not args[0]['artist'] and not args[0]['album'] and LEN_QUEUE > 0:
Jul 19 13:14:17 volumio volumio[1573]: KeyError: 'title'
Jul 19 13:14:17 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 13:14:17 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'.
Jul 19 13:14:17 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart.
Jul 19 13:14:17 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 2.
Jul 19 13:14:17 volumio systemd[1]: Stopped Service for using pirate audio display in volumio.
Jul 19 13:14:17 volumio systemd[1]: Started Service for using pirate audio display in volumio.
Jul 19 13:14:18 volumio ntpd[706]: Soliciting pool server 2a03:b0c0:3:d0::d72:8001
Jul 19 13:14:18 volumio volumio[749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Total Clients: 16
Jul 19 13:14:19 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:19 volumio volumio[749]: info: CoreCommandRouter::volumioGetQueue
Jul 19 13:14:19 volumio volumio[749]: info: CoreStateMachine::getQueue
Jul 19 13:14:19 volumio volumio[749]: info: CorePlayQueue::getQueue
Jul 19 13:14:19 volumio volumio[1619]: register 5
Jul 19 13:14:19 volumio volumio[1619]: success
Jul 19 13:14:19 volumio volumio[1619]: register 6
Jul 19 13:14:19 volumio volumio[1619]: success
Jul 19 13:14:19 volumio volumio[1619]: register 16
Jul 19 13:14:19 volumio volumio[1619]: success
Jul 19 13:14:19 volumio volumio[1619]: register 20
Jul 19 13:14:19 volumio volumio[1619]: success
Jul 19 13:14:19 volumio volumio[1619]: Traceback (most recent call last):
Jul 19 13:14:19 volumio volumio[1619]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 721, in
Jul 19 13:14:19 volumio volumio[1619]: main()
Jul 19 13:14:19 volumio volumio[1619]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 697, in main
Jul 19 13:14:19 volumio volumio[1619]: SOCKETIO.wait()
Jul 19 13:14:19 volumio volumio[1619]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 251, in wait
Jul 19 13:14:19 volumio volumio[1619]: self._process_packets()
Jul 19 13:14:19 volumio volumio[1619]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 278, in _process_packets
Jul 19 13:14:19 volumio volumio[1619]: self._process_packet(engineIO_packet)
Jul 19 13:14:19 volumio volumio[1619]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 491, in _process_packet
Jul 19 13:14:19 volumio volumio[1619]: delegate(parse_socketIO_packet_data(socketIO_packet_data), namespace)
Jul 19 13:14:19 volumio volumio[1619]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 513, in _on_event
Jul 19 13:14:19 volumio volumio[1619]: namespace._find_packet_callback(event)(*args)
Jul 19 13:14:19 volumio volumio[1619]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 368, in on_push_state
Jul 19 13:14:19 volumio volumio[1619]: if not args[0]['title'] and not args[0]['artist'] and not args[0]['album'] and LEN_QUEUE > 0:
Jul 19 13:14:19 volumio volumio[1619]: KeyError: 'title'
Jul 19 13:14:19 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 13:14:19 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'.
Jul 19 13:14:19 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart.
Jul 19 13:14:19 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 3.
Jul 19 13:14:19 volumio systemd[1]: Stopped Service for using pirate audio display in volumio.
Jul 19 13:14:19 volumio systemd[1]: Started Service for using pirate audio display in volumio.
Jul 19 13:14:20 volumio volumio[749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Total Clients: 16
Jul 19 13:14:21 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:21 volumio volumio[749]: info: CoreCommandRouter::volumioGetQueue
Jul 19 13:14:21 volumio volumio[749]: info: CoreStateMachine::getQueue
Jul 19 13:14:21 volumio volumio[749]: info: CorePlayQueue::getQueue
Jul 19 13:14:21 volumio volumio[1663]: register 5
Jul 19 13:14:21 volumio volumio[1663]: success
Jul 19 13:14:21 volumio volumio[1663]: register 6
Jul 19 13:14:21 volumio volumio[1663]: success
Jul 19 13:14:21 volumio volumio[1663]: register 16
Jul 19 13:14:21 volumio volumio[1663]: success
Jul 19 13:14:21 volumio volumio[1663]: register 20
Jul 19 13:14:21 volumio volumio[1663]: success
Jul 19 13:14:21 volumio volumio[1663]: Traceback (most recent call last):
Jul 19 13:14:21 volumio volumio[1663]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 721, in
Jul 19 13:14:21 volumio volumio[1663]: main()
Jul 19 13:14:21 volumio volumio[1663]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 697, in main
Jul 19 13:14:21 volumio volumio[1663]: SOCKETIO.wait()
Jul 19 13:14:21 volumio volumio[1663]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 251, in wait
Jul 19 13:14:21 volumio volumio[1663]: self._process_packets()
Jul 19 13:14:21 volumio volumio[1663]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 278, in _process_packets
Jul 19 13:14:21 volumio volumio[1663]: self._process_packet(engineIO_packet)
Jul 19 13:14:21 volumio volumio[1663]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 491, in _process_packet
Jul 19 13:14:21 volumio volumio[1663]: delegate(parse_socketIO_packet_data(socketIO_packet_data), namespace)
Jul 19 13:14:21 volumio volumio[1663]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 513, in _on_event
Jul 19 13:14:21 volumio volumio[1663]: namespace._find_packet_callback(event)(*args)
Jul 19 13:14:21 volumio volumio[1663]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 368, in on_push_state
Jul 19 13:14:21 volumio volumio[1663]: if not args[0]['title'] and not args[0]['artist'] and not args[0]['album'] and LEN_QUEUE > 0:
Jul 19 13:14:21 volumio volumio[1663]: KeyError: 'title'
Jul 19 13:14:21 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 13:14:21 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'.
Jul 19 13:14:21 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart.
Jul 19 13:14:21 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 4.
Jul 19 13:14:21 volumio systemd[1]: Stopped Service for using pirate audio display in volumio.
Jul 19 13:14:21 volumio systemd[1]: Started Service for using pirate audio display in volumio.
Jul 19 13:14:22 volumio volumio[749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Total Clients: 16
Jul 19 13:14:23 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:23 volumio volumio[749]: info: CoreCommandRouter::volumioGetQueue
Jul 19 13:14:23 volumio volumio[749]: info: CoreStateMachine::getQueue
Jul 19 13:14:23 volumio volumio[749]: info: CorePlayQueue::getQueue
Jul 19 13:14:23 volumio volumio[1707]: register 5
Jul 19 13:14:23 volumio volumio[1707]: success
Jul 19 13:14:23 volumio volumio[1707]: register 6
Jul 19 13:14:23 volumio volumio[1707]: success
Jul 19 13:14:23 volumio volumio[1707]: register 16
Jul 19 13:14:23 volumio volumio[1707]: success
Jul 19 13:14:23 volumio volumio[1707]: register 20
Jul 19 13:14:23 volumio volumio[1707]: success
Jul 19 13:14:23 volumio volumio[1707]: Traceback (most recent call last):
Jul 19 13:14:23 volumio volumio[1707]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 721, in
Jul 19 13:14:23 volumio volumio[1707]: main()
Jul 19 13:14:23 volumio volumio[1707]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 697, in main
Jul 19 13:14:23 volumio volumio[1707]: SOCKETIO.wait()
Jul 19 13:14:23 volumio volumio[1707]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 251, in wait
Jul 19 13:14:23 volumio volumio[1707]: self._process_packets()
Jul 19 13:14:23 volumio volumio[1707]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 278, in _process_packets
Jul 19 13:14:23 volumio volumio[1707]: self._process_packet(engineIO_packet)
Jul 19 13:14:23 volumio volumio[1707]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 491, in _process_packet
Jul 19 13:14:23 volumio volumio[1707]: delegate(parse_socketIO_packet_data(socketIO_packet_data), namespace)
Jul 19 13:14:23 volumio volumio[1707]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 513, in _on_event
Jul 19 13:14:23 volumio volumio[1707]: namespace._find_packet_callback(event)(*args)
Jul 19 13:14:23 volumio volumio[1707]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 368, in on_push_state
Jul 19 13:14:23 volumio volumio[1707]: if not args[0]['title'] and not args[0]['artist'] and not args[0]['album'] and LEN_QUEUE > 0:
Jul 19 13:14:23 volumio volumio[1707]: KeyError: 'title'
Jul 19 13:14:23 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 13:14:23 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'.
Jul 19 13:14:23 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart.
Jul 19 13:14:23 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 5.
Jul 19 13:14:23 volumio systemd[1]: Stopped Service for using pirate audio display in volumio.
Jul 19 13:14:23 volumio systemd[1]: Started Service for using pirate audio display in volumio.
Jul 19 13:14:52 volumio ntpd[706]: receive: Unexpected origin timestamp 0xea44e52f.4747e911 does not match aorg 0000000000.00000000 from server@165.227.160.4 xmt 0xea44e54b.fce62114
Jul 19 13:14:52 volumio volumio[749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Total Clients: 16
Jul 19 13:14:53 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Jul 19 13:14:53 volumio volumio[749]: info: CoreCommandRouter::volumioGetQueue
Jul 19 13:14:53 volumio volumio[749]: info: CoreStateMachine::getQueue
Jul 19 13:14:53 volumio volumio[749]: info: CorePlayQueue::getQueue
Jul 19 13:14:53 volumio volumio[1751]: register 5
Jul 19 13:14:53 volumio volumio[1751]: success
Jul 19 13:14:53 volumio volumio[1751]: register 6
Jul 19 13:14:53 volumio volumio[1751]: success
Jul 19 13:14:53 volumio volumio[1751]: register 16
Jul 19 13:14:53 volumio volumio[1751]: success
Jul 19 13:14:53 volumio volumio[1751]: register 20
Jul 19 13:14:53 volumio volumio[1751]: success
Jul 19 13:14:53 volumio volumio[1751]: Traceback (most recent call last):
Jul 19 13:14:53 volumio volumio[1751]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 721, in
Jul 19 13:14:53 volumio volumio[1751]: main()
Jul 19 13:14:53 volumio volumio[1751]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 697, in main
Jul 19 13:14:53 volumio volumio[1751]: SOCKETIO.wait()
Jul 19 13:14:53 volumio volumio[1751]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 251, in wait
Jul 19 13:14:53 volumio volumio[1751]: self._process_packets()
Jul 19 13:14:53 volumio volumio[1751]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 278, in _process_packets
Jul 19 13:14:53 volumio volumio[1751]: self._process_packet(engineIO_packet)
Jul 19 13:14:53 volumio volumio[1751]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 491, in _process_packet
Jul 19 13:14:53 volumio volumio[1751]: delegate(parse_socketIO_packet_data(socketIO_packet_data), namespace)
Jul 19 13:14:53 volumio volumio[1751]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 513, in _on_event
Jul 19 13:14:53 volumio volumio[1751]: namespace._find_packet_callback(event)(*args)
Jul 19 13:14:53 volumio volumio[1751]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 368, in on_push_state
Jul 19 13:14:53 volumio volumio[1751]: if not args[0]['title'] and not args[0]['artist'] and not args[0]['album'] and LEN_QUEUE > 0:
Jul 19 13:14:53 volumio volumio[1751]: KeyError: 'title'
Jul 19 13:14:53 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE
Jul 19 13:14:53 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'.
Jul 19 13:14:53 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart.
Jul 19 13:14:53 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 6.
Jul 19 13:14:53 volumio systemd[1]: Stopped Service for using pirate audio display in volumio.
Jul 19 13:14:53 volumio systemd[1]: pirateaudio.service: Start request repeated too quickly.
Jul 19 13:14:53 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'.
Jul 19 13:14:53 volumio systemd[1]: Failed to start Service for using pirate audio display in volumio.
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:15:07 volumio volumio[749]: info: Retrieving Cloud Streaming UI
Jul 19 13:15:07 volumio volumio[749]: info: Getting Tidal Cloud Configuration
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:15:07 volumio volumio[749]: info: Getting Qobuz Cloud Configuration
Jul 19 13:15:07 volumio volumio[749]: info: Asking plugin for UI Config
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:15:07 volumio volumio[749]: info: Getting Spotify Cloud Configuration
Jul 19 13:15:07 volumio volumio[749]: info: Asking plugin for UI Config
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:15:07 volumio volumio[749]: info: Saving Spotify Acccount
Jul 19 13:15:07 volumio volumio[749]: info: Got it
Jul 19 13:15:07 volumio volumio[749]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 19 13:15:07 volumio volumio[749]: info: Got Tidal Cloud Configuration
Jul 19 13:15:07 volumio volumio[749]: info: Got it
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 19 13:15:07 volumio volumio[749]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 19 13:15:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:15:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jul 19 13:15:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:15:13 volumio volumio[749]: info: Disabling MyMusic plugin upnp
Jul 19 13:15:13 volumio sudo[1800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Jul 19 13:15:13 volumio sudo[1800]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:15:13 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD...
Jul 19 13:15:13 volumio volumio[749]: ------------------------------------ BT MESSAGE: BT STATUS: running
Jul 19 13:15:13 volumio volumio[749]: error: Upnp client error: Error: This socket has been ended by the other party
Jul 19 13:15:14 volumio volumio[749]: info: Enabling MyMusic plugin upnp
Jul 19 13:15:14 volumio volumio[749]: info: Enabling plugin upnp
Jul 19 13:15:14 volumio volumio[749]: info: Loading plugin "upnp"...
Jul 19 13:15:14 volumio volumio[749]: info: [1721394914046] Starting Upmpd Daemon
Jul 19 13:15:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jul 19 13:15:14 volumio volumio[749]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 19 13:15:14 volumio volumio[749]: Error: listen EADDRINUSE: address already in use :::6599
Jul 19 13:15:14 volumio volumio[749]: at Server.setupListenHandle [as _listen2] (net.js:1318:16)
Jul 19 13:15:14 volumio volumio[749]: at listenInCluster (net.js:1366:12)
Jul 19 13:15:14 volumio volumio[749]: at Server.listen (net.js:1452:7)
Jul 19 13:15:14 volumio volumio[749]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Jul 19 13:15:14 volumio volumio[749]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Jul 19 13:15:14 volumio volumio[749]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Jul 19 13:15:14 volumio volumio[749]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Jul 19 13:15:14 volumio volumio[749]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
Jul 19 13:15:14 volumio volumio[749]: code: 'EADDRINUSE',
Jul 19 13:15:14 volumio volumio[749]: errno: -98,
Jul 19 13:15:14 volumio volumio[749]: syscall: 'listen',
Jul 19 13:15:14 volumio volumio[749]: address: '::',
Jul 19 13:15:14 volumio volumio[749]: port: 6599
Jul 19 13:15:14 volumio volumio[749]: }
Jul 19 13:15:14 volumio volumio[749]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 19 13:15:14 volumio sudo[1811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-19 13:14
Jul 19 13:15:14 volumio sudo[1811]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST"
VOLUMIO_VERSION="3.546"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"