May 31 08:30:00 volumio-markus volumio[1182]: info: MyVolumio token set successfully
May 31 08:30:00 volumio-markus volumio[1182]: info: MYVOLUMIO: Adding device
May 31 08:30:00 volumio-markus volumio[1182]: info: MYVOLUMIO: Evaluating Server
May 31 08:30:01 volumio-markus volumio[1182]: info: MyVolumio status changed
May 31 08:30:01 volumio-markus volumio[1182]: info: Streaming services startup
May 31 08:30:01 volumio-markus volumio[1182]: info: Starting Streaming Daemon
May 31 08:30:01 volumio-markus volumio[1182]: info: Removing browser output: myVolumio user plan is not superstar
May 31 08:30:01 volumio-markus volumio[1182]: info: Removing audio output:
May 31 08:30:01 volumio-markus volumio[1182]: info: Stoppping Tunnel 1
May 31 08:30:01 volumio-markus sudo[1990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 31 08:30:01 volumio-markus sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
May 31 08:30:01 volumio-markus sudo[1992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
May 31 08:30:01 volumio-markus sudo[1992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
May 31 08:30:01 volumio-markus sudo[1990]: pam_unix(sudo:session): session closed for user root
May 31 08:30:01 volumio-markus volumio[1182]: error: Cannot start Volumio Streaming Daemon
May 31 08:30:01 volumio-markus volumio[1182]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 31 08:30:01 volumio-markus volumio[1182]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 31 08:30:01 volumio-markus systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
May 31 08:30:01 volumio-markus systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
May 31 08:30:01 volumio-markus systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
May 31 08:30:01 volumio-markus systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
May 31 08:30:01 volumio-markus systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
May 31 08:30:01 volumio-markus systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
May 31 08:30:01 volumio-markus systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
May 31 08:30:01 volumio-markus systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
May 31 08:30:01 volumio-markus sudo[1992]: pam_unix(sudo:session): session closed for user root
May 31 08:30:01 volumio-markus volumio[1182]: info: Setting Geolocation for MyVolumio to eu4
May 31 08:30:01 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 08:30:01 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 08:30:01 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 08:30:01 volumio-markus volumio[1182]: info: Remote SSH Stopped
May 31 08:30:01 volumio-markus volumio[1182]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
May 31 08:30:01 volumio-markus volumio[1182]: info: Successfully Added MyVolumio device
May 31 08:30:02 volumio-markus volumio[1182]: info: Updating MyVolumio device info
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetVisibleSources
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:02 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 31 08:30:02 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:30:02 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:02 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:02 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:02 volumio-markus volumio[1182]: info: Listing playlists
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 31 08:30:02 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:30:02 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:02 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:30:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 31 08:30:03 volumio-markus volumio[1182]: info: Successfully Updated MyVolumio device
May 31 08:30:04 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 31 08:30:04 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 31 08:30:04 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:30:04 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:30:04 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:30:04 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:30:04 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:04 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:04 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:30:05 volumio-markus systemd[1]: systemd-timedated.service: Deactivated successfully.
May 31 08:30:08 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 31 08:30:08 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 08:30:08 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 31 08:30:08 volumio-markus volumio-remote-updater[590]: No test mode
May 31 08:30:08 volumio-markus volumio-remote-updater[590]: Alpha test mode enabled
May 31 08:30:08 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 31 08:30:08 volumio-markus volumio[1182]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"BREAKING CHANGES
\n\n- New partition layout
\n
\nNEW ADDITIONS
\n\n- Updater 1.8.12
\n- Release channel selector
\n
\nFIXES
\n\n- Upstream Realtek modules
\n- Force updater static libs
\n
\n","title":"Update v4.005","updateavailable":true}
May 31 08:30:08 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 31 08:30:10 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 31 08:30:10 volumio-markus volumio[1182]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
May 31 08:30:10 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:10 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:11 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 31 08:30:13 volumio-markus go-librespot[1569]: time="2025-05-31T08:30:13+02:00" level=trace msg="sent dealer ping"
May 31 08:30:13 volumio-markus go-librespot[1569]: time="2025-05-31T08:30:13+02:00" level=trace msg="received dealer pong"
May 31 08:30:15 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 31 08:30:15 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 31 08:30:15 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 31 08:30:15 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 31 08:30:15 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 31 08:30:15 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
May 31 08:30:15 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetBrowseSources
May 31 08:30:15 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 31 08:30:17 volumio-markus volumio[1182]: error: MyVolumio Plugin failed to authenticate in a timely fashion
May 31 08:30:17 volumio-markus volumio[1182]: info: Completed starting MyVolumio Plugin
May 31 08:30:17 volumio-markus volumio[1182]: [Metrics] CommandRouter: 51s 743.25ms
May 31 08:30:17 volumio-markus volumio[1182]: info: CoreCommandRouter::volumiosetStartupVolume
May 31 08:30:17 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 31 08:30:17 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 31 08:30:17 volumio-markus volumio[1182]: info: CoreCommandRouter::Close All Modals sent
May 31 08:30:17 volumio-markus volumio[1182]: info: CoreCommandRouter::Close All Modals sent
May 31 08:30:17 volumio-markus volumio[1182]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
May 31 08:30:17 volumio-markus volumio[1182]: aplay: main:831: audio open error: Device or resource busy
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 31 08:30:18 volumio-markus volumio[1182]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
May 31 08:30:18 volumio-markus volumio[1182]: info: Received Get System Version
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 31 08:30:18 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:30:18 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:18 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:18 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:30:24 volumio-markus volumio[1182]: info: BOOT COMPLETED
May 31 08:30:26 volumio-markus go-librespot[1569]: time="2025-05-31T08:30:26+02:00" level=debug msg="fetched chunk 4/8, size: 524288" uri="spotify:track:5ZLUm9eab8y3tqQ1OhQSHI"
May 31 08:30:38 volumio-markus systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
May 31 08:30:38 volumio-markus dbus-daemon[558]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.30' (uid=0 pid=2060 comm="timedatectl show --property=NTPSynchronized --valu")
May 31 08:30:38 volumio-markus systemd[1]: Starting systemd-timedated.service - Time & Date Service...
May 31 08:30:38 volumio-markus dbus-daemon[558]: [system] Successfully activated service 'org.freedesktop.timedate1'
May 31 08:30:38 volumio-markus systemd[1]: Started systemd-timedated.service - Time & Date Service.
May 31 08:30:38 volumio-markus setdatetime-helper.sh[2059]: Time is not synchronized. Attempting to sync...
May 31 08:30:39 volumio-markus sudo[2068]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 31 May 2025 06:30:39 GMT#015'
May 31 08:30:39 volumio-markus sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
May 31 08:30:39 volumio-markus setdatetime-helper.sh[2069]: Sat May 31 08:30:39 CEST 2025
May 31 08:30:39 volumio-markus systemd-journald[329]: Time jumped backwards, rotating.
May 31 08:30:39 volumio-markus sudo[2068]: pam_unix(sudo:session): session closed for user root
May 31 08:30:39 volumio-markus setdatetime-helper.sh[2059]: Time synchronized successfully.
May 31 08:30:39 volumio-markus systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
May 31 08:30:39 volumio-markus systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
May 31 08:30:43 volumio-markus go-librespot[1569]: time="2025-05-31T08:30:43+02:00" level=trace msg="sent dealer ping"
May 31 08:30:43 volumio-markus go-librespot[1569]: time="2025-05-31T08:30:43+02:00" level=trace msg="received dealer pong"
May 31 08:30:51 volumio-markus go-librespot[1569]: time="2025-05-31T08:30:51+02:00" level=debug msg="fetched chunk 5/8, size: 524288" uri="spotify:track:5ZLUm9eab8y3tqQ1OhQSHI"
May 31 08:30:56 volumio-markus volumio[1182]: verbose: New Socket.io Connection to 192.168.1.60 from 192.168.1.18 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
May 31 08:30:56 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:30:56 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:56 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:56 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetVisibleSources
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:56 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetQueue
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreStateMachine::getQueue
May 31 08:30:56 volumio-markus volumio[1182]: info: CorePlayQueue::getQueue
May 31 08:30:56 volumio-markus volumio[1182]: info: Listing playlists
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 31 08:30:56 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:30:56 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:56 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:56 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 31 08:30:57 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 31 08:30:57 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:30:57 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:30:57 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:30:57 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:30:57 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:57 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:57 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:30:59 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 31 08:30:59 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:30:59 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:30:59 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:30:59 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:30:59 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:30:59 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:30:59 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:31:02 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
May 31 08:31:02 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:03 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
May 31 08:31:03 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:04 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreStateMachine::ClearQueue
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreStateMachine::stPlaybackTimer
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreStateMachine::updateTrackBlock
May 31 08:31:04 volumio-markus volumio[1182]: info: CorePlayQueue::getTrackBlock
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:04 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreStateMachine::serviceStop
May 31 08:31:04 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreCommandRouter::serviceStop
May 31 08:31:04 volumio-markus volumio[1182]: info: Spotify Stop
May 31 08:31:04 volumio-markus volumio[1182]: SPOTIFY: SPOTIFY STOP
May 31 08:31:04 volumio-markus volumio[1182]: SPOTIFY: {"status":"play","position":0,"title":"Abracadabra","artist":"Lady Gaga","album":"Abracadabra","albumart":"https://i.scdn.co/image/ab67616d0000b27325304f6dc9dea023ce4b985c","uri":"spotify:track:5ZLUm9eab8y3tqQ1OhQSHI","trackType":"spotify","codec":"ogg","seek":0,"duration":223,"samplerate":"160 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"}
May 31 08:31:04 volumio-markus volumio[1182]: info: Sending Spotify command to local API: /player/pause
May 31 08:31:04 volumio-markus volumio[1182]: info: CorePlayQueue::clearPlayQueue
May 31 08:31:04 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreStateMachine::addQueueItems
May 31 08:31:04 volumio-markus volumio[1182]: info: CorePlayQueue::addQueueItems
May 31 08:31:04 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:04 volumio-markus volumio[1182]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s15033
May 31 08:31:04 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 31 08:31:05 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:05+02:00" level=debug msg="pause track at 66117ms"
May 31 08:31:05 volumio-markus volumio[1182]: info: touch_display: Setting screensaver timeout to 120 seconds.
May 31 08:31:05 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:05+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
May 31 08:31:05 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:05+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
May 31 08:31:05 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:05+02:00" level=trace msg="emitting websocket event: paused"
May 31 08:31:05 volumio-markus volumio[1182]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5ZLUm9eab8y3tqQ1OhQSHI","play_origin":"go-librespot"}}
May 31 08:31:05 volumio-markus volumio[1182]: SPOTIFY: PUSH STATE SPOTIFY
May 31 08:31:05 volumio-markus volumio[1182]: SPOTIFY: {"status":"pause","service":"spop","title":"Abracadabra","artist":"Lady Gaga","album":"Abracadabra","albumart":"https://i.scdn.co/image/ab67616d00001e0225304f6dc9dea023ce4b985c","uri":"spotify:track:5ZLUm9eab8y3tqQ1OhQSHI","trackType":"spotify","seek":65000,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:05 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:05 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Abracadabra","artist":"Lady Gaga","album":"Abracadabra","albumart":"https://i.scdn.co/image/ab67616d00001e0225304f6dc9dea023ce4b985c","uri":"spotify:track:5ZLUm9eab8y3tqQ1OhQSHI","trackType":"spotify","seek":65000,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
May 31 08:31:05 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:05 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:05 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::updateTrackBlock
May 31 08:31:05 volumio-markus volumio[1182]: info: CorePlayQueue::getTrackBlock
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::play index 0
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:05 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::startPlaybackTimer
May 31 08:31:05 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:05 volumio-markus volumio[1182]: info: [1748673065347] ControllerWebradio::clearAddPlayTrack
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop
May 31 08:31:05 volumio-markus volumio[1182]: info: sendMpdCommand stop took 7 milliseconds
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear
May 31 08:31:05 volumio-markus volumio[1182]: info: sendMpdCommand clear took 0 milliseconds
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com"
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:05 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:05 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:05 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 31 08:31:05 volumio-markus mpd[1453]: exception: No such playlist
May 31 08:31:05 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:05 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:05 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com"
May 31 08:31:05 volumio-markus volumio[1182]: info: ------------------------------ 352ms
May 31 08:31:05 volumio-markus volumio[1182]: info: ------------------------------ 351ms
May 31 08:31:05 volumio-markus volumio[1182]: info: ------------------------------ 346ms
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:05 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:05 volumio-markus volumio[1182]: info: sendMpdCommand add "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 10 milliseconds
May 31 08:31:05 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService mpd
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand play
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:05 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:05 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:05 volumio-markus volumio[1182]: info: ------------------------------ 22ms
May 31 08:31:05 volumio-markus volumio[1182]: info: sendMpdCommand play took 23 milliseconds
May 31 08:31:05 volumio-markus volumio[1182]: info: ------------------------------ 22ms
May 31 08:31:05 volumio-markus volumio[1182]: info: ------------------------------ 21ms
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:05 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:05 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:05 volumio-markus mpd[1453]: player: problems opening audio device while playing "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com"
May 31 08:31:05 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:05 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:05 volumio-markus volumio[1182]: info:
May 31 08:31:05 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:05 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:05 volumio-markus volumio[1182]: info: sendMpdCommand status took 68 milliseconds
May 31 08:31:05 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:05 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:06 volumio-markus volumio[1182]: info:
May 31 08:31:06 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:06 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:06 volumio-markus volumio[1182]: info:
May 31 08:31:06 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:06 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand status took 136 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand status took 166 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 109 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand status took 105 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 78 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand status took 42 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand status took 40 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:06 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:06 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"}
May 31 08:31:06 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:06 volumio-markus volumio[1182]: info: ------------------------------ 295ms
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 184 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 150 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 126 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 125 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 125 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 105 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 105 milliseconds
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:06 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:06 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:06 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"}
May 31 08:31:06 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:06 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:06 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"}
May 31 08:31:06 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:06 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:06 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"}
May 31 08:31:06 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:06 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:06 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"}
May 31 08:31:06 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:06 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:06 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"}
May 31 08:31:06 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:06 volumio-markus volumio[1182]: info: ------------------------------ 508ms
May 31 08:31:06 volumio-markus volumio[1182]: info: ------------------------------ 502ms
May 31 08:31:06 volumio-markus volumio[1182]: info: ------------------------------ 500ms
May 31 08:31:06 volumio-markus volumio[1182]: info: ------------------------------ 409ms
May 31 08:31:06 volumio-markus volumio[1182]: info: ------------------------------ 374ms
May 31 08:31:06 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 31 08:31:08 volumio-markus systemd[1]: systemd-timedated.service: Deactivated successfully.
May 31 08:31:10 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:10 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 31 08:31:10 volumio-markus volumio[1182]: info: CoreStateMachine::ClearQueue
May 31 08:31:10 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:10 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:10 volumio-markus volumio[1182]: info: CorePlayQueue::clearPlayQueue
May 31 08:31:10 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:10 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:10 volumio-markus volumio[1182]: info: CoreStateMachine::addQueueItems
May 31 08:31:10 volumio-markus volumio[1182]: info: CorePlayQueue::addQueueItems
May 31 08:31:10 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:10 volumio-markus volumio[1182]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s15024
May 31 08:31:10 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:11 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreStateMachine::updateTrackBlock
May 31 08:31:11 volumio-markus volumio[1182]: info: CorePlayQueue::getTrackBlock
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreStateMachine::play index 0
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:11 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:11 volumio-markus volumio[1182]: info: CoreStateMachine::startPlaybackTimer
May 31 08:31:11 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:11 volumio-markus volumio[1182]: info: [1748673071165] ControllerWebradio::clearAddPlayTrack
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop
May 31 08:31:11 volumio-markus mpd[1453]: player: played "http://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com"
May 31 08:31:11 volumio-markus volumio[1182]: info:
May 31 08:31:11 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:11 volumio-markus volumio[1182]: info: sendMpdCommand stop took 3 milliseconds
May 31 08:31:11 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear
May 31 08:31:11 volumio-markus volumio[1182]: info:
May 31 08:31:11 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:11 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:11 volumio-markus volumio[1182]: info: sendMpdCommand status took 1 milliseconds
May 31 08:31:11 volumio-markus volumio[1182]: info: sendMpdCommand clear took 1 milliseconds
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:11 volumio-markus volumio[1182]: info:
May 31 08:31:11 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:11 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:11 volumio-markus volumio[1182]: info:
May 31 08:31:11 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:11 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:11 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:11 volumio-markus volumio[1182]: info: ------------------------------ 42ms
May 31 08:31:11 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 42 milliseconds
May 31 08:31:11 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:11 volumio-markus volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split')
May 31 08:31:11 volumio-markus volumio[1182]: info: ------------------------------ 46ms
May 31 08:31:12 volumio-markus mpd[1453]: exception: No such playlist
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:12 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:12 volumio-markus volumio[1182]: info: sendMpdCommand status took 1144 milliseconds
May 31 08:31:12 volumio-markus volumio[1182]: info: sendMpdCommand status took 1143 milliseconds
May 31 08:31:12 volumio-markus volumio[1182]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" took 2 milliseconds
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService mpd
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand play
May 31 08:31:12 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:12 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 31 08:31:12 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService stop
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:12 volumio-markus volumio[1182]: info: No code
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:12 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:12 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 31 08:31:12 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService stop
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:12 volumio-markus volumio[1182]: info: No code
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:12 volumio-markus volumio[1182]: info: ------------------------------ 1392ms
May 31 08:31:12 volumio-markus volumio[1182]: info: ------------------------------ 1371ms
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:12 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:12 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:12 volumio-markus volumio[1182]: info: ------------------------------ 244ms
May 31 08:31:12 volumio-markus volumio[1182]: info: sendMpdCommand play took 242 milliseconds
May 31 08:31:12 volumio-markus volumio[1182]: info: ------------------------------ 15ms
May 31 08:31:12 volumio-markus volumio[1182]: info: ------------------------------ 11ms
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:12 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:12 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:12 volumio-markus volumio[1182]: info: ------------------------------ 67ms
May 31 08:31:12 volumio-markus volumio[1182]: info: ------------------------------ 66ms
May 31 08:31:12 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::ClearQueue
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::clearPlayQueue
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::addQueueItems
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::addQueueItems
May 31 08:31:12 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:12 volumio-markus volumio[1182]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s15024
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::updateTrackBlock
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::getTrackBlock
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::play index 0
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:12 volumio-markus volumio[1182]: info: CoreStateMachine::startPlaybackTimer
May 31 08:31:12 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:12 volumio-markus volumio[1182]: info: [1748673072952] ControllerWebradio::clearAddPlayTrack
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop
May 31 08:31:12 volumio-markus mpd[1453]: player: played "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:12 volumio-markus volumio[1182]: info: sendMpdCommand stop took 21 milliseconds
May 31 08:31:12 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:12 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:12 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:12 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:12 volumio-markus volumio[1182]: info:
May 31 08:31:12 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:12 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:12 volumio-markus volumio[1182]: info: sendMpdCommand status took 22 milliseconds
May 31 08:31:12 volumio-markus volumio[1182]: info: sendMpdCommand clear took 19 milliseconds
May 31 08:31:13 volumio-markus volumio[1182]: info: sendMpdCommand status took 11 milliseconds
May 31 08:31:13 volumio-markus volumio[1182]: info: sendMpdCommand status took 10 milliseconds
May 31 08:31:13 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:13 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:13 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:13 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:13 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:13 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:13 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:13 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:13 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:13 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:13 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:13 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:13 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 151ms
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 143ms
May 31 08:31:13 volumio-markus volumio[1182]: info:
May 31 08:31:13 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:13 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:13 volumio-markus volumio[1182]: info:
May 31 08:31:13 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:13 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:13 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 173ms
May 31 08:31:13 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 162 milliseconds
May 31 08:31:13 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:13 volumio-markus volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split')
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 201ms
May 31 08:31:13 volumio-markus mpd[1453]: exception: No such playlist
May 31 08:31:13 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:13 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:13 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 242ms
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 242ms
May 31 08:31:13 volumio-markus volumio[1182]: info:
May 31 08:31:13 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:13 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:13 volumio-markus volumio[1182]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" took 1 milliseconds
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService mpd
May 31 08:31:13 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand play
May 31 08:31:13 volumio-markus volumio[1182]: info:
May 31 08:31:13 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:13 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:13 volumio-markus volumio[1182]: info:
May 31 08:31:13 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:13 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 27ms
May 31 08:31:13 volumio-markus volumio[1182]: info: sendMpdCommand play took 10 milliseconds
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 2ms
May 31 08:31:13 volumio-markus volumio[1182]: info: ------------------------------ 2ms
May 31 08:31:13 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:13+02:00" level=trace msg="sent dealer ping"
May 31 08:31:13 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:13+02:00" level=trace msg="received dealer pong"
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 31 08:31:13 volumio-markus volumio[1182]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
May 31 08:31:13 volumio-markus volumio[1182]: info: Received Get System Version
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 31 08:31:13 volumio-markus volumio[1182]: info: Received Get System Info
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 31 08:31:13 volumio-markus volumio[1182]: info: Discovery: Getting this device information
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioGetState
May 31 08:31:13 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 31 08:31:14 volumio-markus volumio[1182]: info:
May 31 08:31:14 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:14 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:14 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:14 volumio-markus mpd[1453]: player: problems opening audio device while playing "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:14 volumio-markus volumio[1182]: info:
May 31 08:31:14 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:14 volumio-markus volumio[1182]: info:
May 31 08:31:14 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:14 volumio-markus volumio[1182]: info:
May 31 08:31:14 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand status took 21 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:14 volumio-markus volumio[1182]: info:
May 31 08:31:14 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:14 volumio-markus volumio[1182]: info:
May 31 08:31:14 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand status took 53 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand status took 53 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 36 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand status took 33 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 32 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand status took 26 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand status took 25 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:14 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:14 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:14 volumio-markus volumio[1182]: info: ------------------------------ 165ms
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 140 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 140 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 117 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 99 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 99 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 98 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 99 milliseconds
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:14 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:14 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:14 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:14 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:14 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:14 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:14 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:14 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:14 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:14 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:14 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:14 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:14 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:14 volumio-markus volumio[1182]: info: ------------------------------ 294ms
May 31 08:31:14 volumio-markus volumio[1182]: info: ------------------------------ 293ms
May 31 08:31:14 volumio-markus volumio[1182]: info: ------------------------------ 293ms
May 31 08:31:14 volumio-markus volumio[1182]: info: ------------------------------ 266ms
May 31 08:31:14 volumio-markus volumio[1182]: info: ------------------------------ 266ms
May 31 08:31:23 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::ClearQueue
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:23 volumio-markus volumio[1182]: info: CorePlayQueue::clearPlayQueue
May 31 08:31:23 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::addQueueItems
May 31 08:31:23 volumio-markus volumio[1182]: info: CorePlayQueue::addQueueItems
May 31 08:31:23 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:23 volumio-markus volumio[1182]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s15024
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:23 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::updateTrackBlock
May 31 08:31:23 volumio-markus volumio[1182]: info: CorePlayQueue::getTrackBlock
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::play index 0
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:23 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:23 volumio-markus volumio[1182]: info: CoreStateMachine::startPlaybackTimer
May 31 08:31:23 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:23 volumio-markus volumio[1182]: info: [1748673083502] ControllerWebradio::clearAddPlayTrack
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop
May 31 08:31:23 volumio-markus mpd[1453]: player: played "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:23 volumio-markus volumio[1182]: info:
May 31 08:31:23 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:23 volumio-markus volumio[1182]: info: sendMpdCommand stop took 23 milliseconds
May 31 08:31:23 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear
May 31 08:31:23 volumio-markus volumio[1182]: info:
May 31 08:31:23 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:23 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:23 volumio-markus volumio[1182]: info: sendMpdCommand status took 9 milliseconds
May 31 08:31:23 volumio-markus volumio[1182]: info: sendMpdCommand clear took 6 milliseconds
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:23 volumio-markus volumio[1182]: info:
May 31 08:31:23 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:23 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:23 volumio-markus volumio[1182]: info:
May 31 08:31:23 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:23 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:23 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:23 volumio-markus volumio[1182]: info: ------------------------------ 24ms
May 31 08:31:23 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 19 milliseconds
May 31 08:31:23 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:23 volumio-markus volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split')
May 31 08:31:23 volumio-markus volumio[1182]: info: ------------------------------ 39ms
May 31 08:31:24 volumio-markus mpd[1453]: exception: No such playlist
May 31 08:31:24 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:24 volumio-markus volumio[1182]: info: sendMpdCommand status took 776 milliseconds
May 31 08:31:24 volumio-markus volumio[1182]: info: sendMpdCommand status took 777 milliseconds
May 31 08:31:24 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:24 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:24 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:24 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:24 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:24 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:24 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:24 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:24 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:24 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:24 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:24 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:24 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:24 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:24 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:24 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:24 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:24 volumio-markus volumio[1182]: info: ------------------------------ 869ms
May 31 08:31:24 volumio-markus volumio[1182]: info: ------------------------------ 917ms
May 31 08:31:24 volumio-markus volumio[1182]: info:
May 31 08:31:24 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:24 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:24 volumio-markus volumio[1182]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" took 144 milliseconds
May 31 08:31:24 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService mpd
May 31 08:31:24 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand play
May 31 08:31:24 volumio-markus volumio[1182]: info:
May 31 08:31:24 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:24 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:24 volumio-markus volumio[1182]: info:
May 31 08:31:24 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:24 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:24 volumio-markus volumio[1182]: info: ------------------------------ 62ms
May 31 08:31:24 volumio-markus volumio[1182]: info: sendMpdCommand play took 62 milliseconds
May 31 08:31:24 volumio-markus volumio[1182]: info: ------------------------------ 61ms
May 31 08:31:24 volumio-markus volumio[1182]: info: ------------------------------ 60ms
May 31 08:31:24 volumio-markus volumio[1182]: info:
May 31 08:31:24 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:24 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:24 volumio-markus volumio[1182]: info:
May 31 08:31:24 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:24 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:24 volumio-markus volumio[1182]: info: ------------------------------ 2ms
May 31 08:31:24 volumio-markus volumio[1182]: info: ------------------------------ 2ms
May 31 08:31:25 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:25 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:25 volumio-markus mpd[1453]: player: problems opening audio device while playing "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 23 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 78 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 78 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 57 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 57 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 68 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 67 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 66 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:25 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 181ms
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 164 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 163 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 134 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 133 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 133 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 132 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 133 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:25 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:25 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:25 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:25 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:25 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 411ms
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 410ms
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 410ms
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 388ms
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 387ms
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::startPlaybackTimer
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: info: [1748673085750] ControllerWebradio::clearAddPlayTrack
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop
May 31 08:31:25 volumio-markus mpd[1453]: player: played "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand stop took 64 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:25 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 3 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand clear took 3 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 2 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand status took 2 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:25 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:25 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:25 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 92ms
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 92ms
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:25 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:25 volumio-markus volumio[1182]: info:
May 31 08:31:25 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:25 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:25 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 93ms
May 31 08:31:25 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 93 milliseconds
May 31 08:31:25 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:25 volumio-markus volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split')
May 31 08:31:25 volumio-markus volumio[1182]: info: ------------------------------ 99ms
May 31 08:31:26 volumio-markus mpd[1453]: exception: No such playlist
May 31 08:31:26 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:26 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:26 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:26 volumio-markus volumio[1182]: info:
May 31 08:31:26 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:26 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:26 volumio-markus volumio[1182]: info: ------------------------------ 426ms
May 31 08:31:26 volumio-markus volumio[1182]: info: ------------------------------ 425ms
May 31 08:31:26 volumio-markus volumio[1182]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein" took 1 milliseconds
May 31 08:31:26 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService mpd
May 31 08:31:26 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand play
May 31 08:31:26 volumio-markus volumio[1182]: info:
May 31 08:31:26 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:26 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:26 volumio-markus volumio[1182]: info: ------------------------------ 14ms
May 31 08:31:26 volumio-markus volumio[1182]: info: sendMpdCommand play took 13 milliseconds
May 31 08:31:26 volumio-markus volumio[1182]: info: ------------------------------ 1ms
May 31 08:31:26 volumio-markus volumio[1182]: info:
May 31 08:31:26 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:26 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:26 volumio-markus volumio[1182]: info: ------------------------------ 7ms
May 31 08:31:27 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:27 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:27 volumio-markus mpd[1453]: player: problems opening audio device while playing "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:27 volumio-markus volumio[1182]: info:
May 31 08:31:27 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:27 volumio-markus volumio[1182]: info:
May 31 08:31:27 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:27 volumio-markus volumio[1182]: info:
May 31 08:31:27 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:27 volumio-markus volumio[1182]: info:
May 31 08:31:27 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:27 volumio-markus volumio[1182]: info:
May 31 08:31:27 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:27 volumio-markus volumio[1182]: info:
May 31 08:31:27 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand status took 53 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand status took 52 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand status took 36 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand status took 48 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand status took 113 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 81 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand status took 80 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 81 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 80 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 68 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 25 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 25 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 24 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 23 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:27 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:27 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:27 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:27 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:27 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:27 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:27 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:27 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:27 volumio-markus volumio[1182]: info: ------------------------------ 232ms
May 31 08:31:27 volumio-markus volumio[1182]: info: ------------------------------ 241ms
May 31 08:31:27 volumio-markus volumio[1182]: info: ------------------------------ 242ms
May 31 08:31:27 volumio-markus volumio[1182]: info: ------------------------------ 226ms
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 150 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 159 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 161 milliseconds
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:27 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:27 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:27 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:27 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:27 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"low?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein","trackType":""}
May 31 08:31:27 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:27 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:27 volumio-markus volumio[1182]: info: ------------------------------ 335ms
May 31 08:31:27 volumio-markus volumio[1182]: info: ------------------------------ 337ms
May 31 08:31:30 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::ClearQueue
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:30 volumio-markus volumio[1182]: info: CorePlayQueue::clearPlayQueue
May 31 08:31:30 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::addQueueItems
May 31 08:31:30 volumio-markus volumio[1182]: info: CorePlayQueue::addQueueItems
May 31 08:31:30 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:30 volumio-markus volumio[1182]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s78373
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:30 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::updateTrackBlock
May 31 08:31:30 volumio-markus volumio[1182]: info: CorePlayQueue::getTrackBlock
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::play index 0
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:30 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:30 volumio-markus volumio[1182]: info: CoreStateMachine::startPlaybackTimer
May 31 08:31:30 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:30 volumio-markus volumio[1182]: info: [1748673090989] ControllerWebradio::clearAddPlayTrack
May 31 08:31:30 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop
May 31 08:31:30 volumio-markus mpd[1453]: player: played "https://dispatcher.rndfnk.com/br/br3/live/mp3/low?aggregator=tunein"
May 31 08:31:31 volumio-markus volumio[1182]: info:
May 31 08:31:31 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:31 volumio-markus volumio[1182]: info: sendMpdCommand stop took 22 milliseconds
May 31 08:31:31 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear
May 31 08:31:31 volumio-markus volumio[1182]: info:
May 31 08:31:31 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:31 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:31 volumio-markus volumio[1182]: info:
May 31 08:31:31 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:31 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:31 volumio-markus volumio[1182]: info:
May 31 08:31:31 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:31 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:31 volumio-markus volumio[1182]: info: sendMpdCommand status took 7 milliseconds
May 31 08:31:31 volumio-markus volumio[1182]: info: sendMpdCommand clear took 7 milliseconds
May 31 08:31:31 volumio-markus volumio[1182]: info: sendMpdCommand status took 6 milliseconds
May 31 08:31:31 volumio-markus volumio[1182]: info: sendMpdCommand status took 7 milliseconds
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand load "https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein"
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:31 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:31 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:31 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:31 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:31 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:31 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:31 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:31 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:31 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:31 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:31 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:31 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:31 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:31 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:31 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:31 volumio-markus volumio[1182]: info: ------------------------------ 136ms
May 31 08:31:31 volumio-markus volumio[1182]: info: ------------------------------ 135ms
May 31 08:31:31 volumio-markus volumio[1182]: info:
May 31 08:31:31 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:31 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:31 volumio-markus volumio[1182]: info:
May 31 08:31:31 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:31 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:31 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:31 volumio-markus volumio[1182]: info: ------------------------------ 132ms
May 31 08:31:31 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 131 milliseconds
May 31 08:31:31 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:31 volumio-markus volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split')
May 31 08:31:31 volumio-markus volumio[1182]: info: ------------------------------ 141ms
May 31 08:31:32 volumio-markus mpd[1453]: exception: No such playlist
May 31 08:31:32 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:32 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:32 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein"
May 31 08:31:32 volumio-markus volumio[1182]: info: ------------------------------ 1298ms
May 31 08:31:32 volumio-markus volumio[1182]: info: ------------------------------ 1298ms
May 31 08:31:32 volumio-markus volumio[1182]: info:
May 31 08:31:32 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:32 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:32 volumio-markus volumio[1182]: info: sendMpdCommand add "https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein" took 2 milliseconds
May 31 08:31:32 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService mpd
May 31 08:31:32 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand play
May 31 08:31:32 volumio-markus volumio[1182]: info:
May 31 08:31:32 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:32 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:32 volumio-markus volumio[1182]: info:
May 31 08:31:32 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:32 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:32 volumio-markus volumio[1182]: info: ------------------------------ 4ms
May 31 08:31:32 volumio-markus volumio[1182]: info: sendMpdCommand play took 24 milliseconds
May 31 08:31:32 volumio-markus volumio[1182]: info: ------------------------------ 24ms
May 31 08:31:32 volumio-markus volumio[1182]: info: ------------------------------ 24ms
May 31 08:31:33 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:33 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:33 volumio-markus mpd[1453]: player: problems opening audio device while playing "https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein"
May 31 08:31:33 volumio-markus volumio[1182]: info:
May 31 08:31:33 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:33 volumio-markus volumio[1182]: info:
May 31 08:31:33 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:33 volumio-markus volumio[1182]: info:
May 31 08:31:33 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:33 volumio-markus volumio[1182]: info:
May 31 08:31:33 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:33 volumio-markus volumio[1182]: info:
May 31 08:31:33 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand status took 38 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand status took 49 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand status took 50 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand status took 63 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:33 volumio-markus volumio[1182]: info:
May 31 08:31:33 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 124 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand status took 123 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 112 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 111 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 97 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 36 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 36 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 36 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 35 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand status took 2 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:33 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein","trackType":""}
May 31 08:31:33 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:33 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein","trackType":""}
May 31 08:31:33 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:33 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein","trackType":""}
May 31 08:31:33 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:33 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein","trackType":""}
May 31 08:31:33 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:33 volumio-markus volumio[1182]: info: ------------------------------ 344ms
May 31 08:31:33 volumio-markus volumio[1182]: info: ------------------------------ 343ms
May 31 08:31:33 volumio-markus volumio[1182]: info: ------------------------------ 381ms
May 31 08:31:33 volumio-markus volumio[1182]: info: ------------------------------ 380ms
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 269 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 268 milliseconds
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:33 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:33 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein","trackType":""}
May 31 08:31:33 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:33 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:33 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein","trackType":""}
May 31 08:31:33 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:33 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:33 volumio-markus volumio[1182]: info: ------------------------------ 512ms
May 31 08:31:33 volumio-markus volumio[1182]: info: ------------------------------ 397ms
May 31 08:31:34 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:34 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 31 08:31:34 volumio-markus volumio[1182]: info: CoreStateMachine::ClearQueue
May 31 08:31:34 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:34 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:34 volumio-markus volumio[1182]: info: CorePlayQueue::clearPlayQueue
May 31 08:31:34 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:34 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:34 volumio-markus volumio[1182]: info: CoreStateMachine::addQueueItems
May 31 08:31:34 volumio-markus volumio[1182]: info: CorePlayQueue::addQueueItems
May 31 08:31:34 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:34 volumio-markus volumio[1182]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s132854
May 31 08:31:34 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:35 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreStateMachine::updateTrackBlock
May 31 08:31:35 volumio-markus volumio[1182]: info: CorePlayQueue::getTrackBlock
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreStateMachine::play index 0
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:35 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:35 volumio-markus volumio[1182]: info: CoreStateMachine::startPlaybackTimer
May 31 08:31:35 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:35 volumio-markus volumio[1182]: info: [1748673095156] ControllerWebradio::clearAddPlayTrack
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop
May 31 08:31:35 volumio-markus mpd[1453]: player: played "https://frontend.streamonkey.net/fhn-gong971/stream/mp3?aggregator=tunein"
May 31 08:31:35 volumio-markus volumio[1182]: info:
May 31 08:31:35 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:35 volumio-markus volumio[1182]: info: sendMpdCommand stop took 3 milliseconds
May 31 08:31:35 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear
May 31 08:31:35 volumio-markus volumio[1182]: info:
May 31 08:31:35 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:35 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:35 volumio-markus volumio[1182]: info: sendMpdCommand status took 1 milliseconds
May 31 08:31:35 volumio-markus volumio[1182]: info: sendMpdCommand clear took 1 milliseconds
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand load "https://stream.klassikradio.de/national/mp3-192/tunein"
May 31 08:31:35 volumio-markus volumio[1182]: info:
May 31 08:31:35 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:35 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:35 volumio-markus volumio[1182]: info:
May 31 08:31:35 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:35 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:35 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:35 volumio-markus volumio[1182]: info: ------------------------------ 26ms
May 31 08:31:35 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 25 milliseconds
May 31 08:31:35 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:35 volumio-markus volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split')
May 31 08:31:35 volumio-markus volumio[1182]: info: ------------------------------ 60ms
May 31 08:31:36 volumio-markus mpd[1453]: exception: No such playlist
May 31 08:31:36 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "https://stream.klassikradio.de/national/mp3-192/tunein"
May 31 08:31:36 volumio-markus volumio[1182]: info: sendMpdCommand status took 1476 milliseconds
May 31 08:31:36 volumio-markus volumio[1182]: info: sendMpdCommand status took 1477 milliseconds
May 31 08:31:36 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:36 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:36 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:36 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:36 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:36 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:36 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:36 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:36 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:36 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:36 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:36 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:36 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:36 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:36 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:36 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:36 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 31 08:31:36 volumio-markus volumio[1182]: info: ------------------------------ 1569ms
May 31 08:31:36 volumio-markus volumio[1182]: info: ------------------------------ 1547ms
May 31 08:31:36 volumio-markus volumio[1182]: info:
May 31 08:31:36 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:36 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:36 volumio-markus volumio[1182]: info:
May 31 08:31:36 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:36 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:36 volumio-markus volumio[1182]: info:
May 31 08:31:36 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:36 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:36 volumio-markus volumio[1182]: info: sendMpdCommand add "https://stream.klassikradio.de/national/mp3-192/tunein" took 104 milliseconds
May 31 08:31:36 volumio-markus volumio[1182]: info: ------------------------------ 32ms
May 31 08:31:36 volumio-markus volumio[1182]: info: ------------------------------ 13ms
May 31 08:31:36 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService mpd
May 31 08:31:36 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand play
May 31 08:31:36 volumio-markus volumio[1182]: info:
May 31 08:31:36 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:36 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:36 volumio-markus volumio[1182]: info:
May 31 08:31:36 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:36 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:36 volumio-markus volumio[1182]: info: ------------------------------ 24ms
May 31 08:31:36 volumio-markus volumio[1182]: info: sendMpdCommand play took 24 milliseconds
May 31 08:31:36 volumio-markus volumio[1182]: info: ------------------------------ 24ms
May 31 08:31:36 volumio-markus volumio[1182]: info: ------------------------------ 7ms
May 31 08:31:37 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:37 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:37 volumio-markus mpd[1453]: player: problems opening audio device while playing "https://stream.klassikradio.de/national/mp3-192/tunein"
May 31 08:31:37 volumio-markus volumio[1182]: info:
May 31 08:31:37 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:37 volumio-markus volumio[1182]: info:
May 31 08:31:37 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:37 volumio-markus volumio[1182]: info:
May 31 08:31:37 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:37 volumio-markus volumio[1182]: info:
May 31 08:31:37 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand status took 40 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:37 volumio-markus volumio[1182]: info:
May 31 08:31:37 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:37 volumio-markus volumio[1182]: info:
May 31 08:31:37 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand status took 80 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand status took 80 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 42 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand status took 41 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 41 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand status took 40 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand status took 39 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:37 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein","artist":null,"album":null,"uri":"https://stream.klassikradio.de/national/mp3-192/tunein","trackType":""}
May 31 08:31:37 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:37 volumio-markus volumio[1182]: info: ------------------------------ 191ms
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 163 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 162 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 157 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 157 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 157 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 157 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 156 milliseconds
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:37 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:37 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein","artist":null,"album":null,"uri":"https://stream.klassikradio.de/national/mp3-192/tunein","trackType":""}
May 31 08:31:37 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:37 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein","artist":null,"album":null,"uri":"https://stream.klassikradio.de/national/mp3-192/tunein","trackType":""}
May 31 08:31:37 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:37 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein","artist":null,"album":null,"uri":"https://stream.klassikradio.de/national/mp3-192/tunein","trackType":""}
May 31 08:31:37 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:37 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:37 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein","artist":null,"album":null,"uri":"https://stream.klassikradio.de/national/mp3-192/tunein","trackType":""}
May 31 08:31:37 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:37 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:38 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:38 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:38 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:38 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein","artist":null,"album":null,"uri":"https://stream.klassikradio.de/national/mp3-192/tunein","trackType":""}
May 31 08:31:38 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:38 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService pause
May 31 08:31:38 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:38 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:38 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:38 volumio-markus volumio[1182]: info: ------------------------------ 416ms
May 31 08:31:38 volumio-markus volumio[1182]: info: ------------------------------ 416ms
May 31 08:31:38 volumio-markus volumio[1182]: info: ------------------------------ 416ms
May 31 08:31:38 volumio-markus volumio[1182]: info: ------------------------------ 376ms
May 31 08:31:38 volumio-markus volumio[1182]: info: ------------------------------ 401ms
May 31 08:31:42 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri
May 31 08:31:42 volumio-markus volumio[1182]: info: [1748673102069] [80s80s] handleBrowseUri curUri: 80s80s
May 31 08:31:42 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:43 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri
May 31 08:31:43 volumio-markus volumio[1182]: info: [1748673103268] [80s80s] handleBrowseUri curUri: 80s80s/nineties
May 31 08:31:43 volumio-markus volumio[1182]: info: [1748673103268] [80s80s] getRadioContent url: nineties
May 31 08:31:43 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:43 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:43+02:00" level=trace msg="received accesspoint ping"
May 31 08:31:43 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:43+02:00" level=trace msg="received accesspoint pong ack"
May 31 08:31:43 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:43+02:00" level=trace msg="sent dealer ping"
May 31 08:31:43 volumio-markus go-librespot[1569]: time="2025-05-31T08:31:43+02:00" level=trace msg="received dealer pong"
May 31 08:31:44 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::ClearQueue
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::clearPlayQueue
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::addQueueItems
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::addQueueItems
May 31 08:31:44 volumio-markus volumio[1182]: info: Preload queue cleared
May 31 08:31:44 volumio-markus volumio[1182]: info: Adding Item to queue: webnineties/0
May 31 08:31:44 volumio-markus volumio[1182]: info: Exploding uri webnineties/0 in service 80s80s
May 31 08:31:44 volumio-markus volumio[1182]: info: [1748673104116] [80s80s] explodeUri: webnineties/0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushQueue
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::saveQueue
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::updateTrackBlock
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrackBlock
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::play index 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::stop
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::startPlaybackTimer
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: [1748673104156] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/pop/mp3-192/volumio
May 31 08:31:44 volumio-markus volumio[1182]: info: [1748673104156] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=141&count=2
May 31 08:31:44 volumio-markus volumio[1182]: info: [1748673104323] [80s80s] received new event containing 2 songs.
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop
May 31 08:31:44 volumio-markus mpd[1453]: player: played "https://stream.klassikradio.de/national/mp3-192/tunein"
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand stop took 13 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:44 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand status took 10 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand clear took 10 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand status took 12 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand status took 5 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand consume 1
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 114ms
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 107ms
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:44 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:44 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:44 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: options
May 31 08:31:44 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:44 volumio-markus volumio[1182]: error: updateQueue error: null
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 127ms
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 123 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand consume 1 took 123 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 20ms
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 9ms
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:44 volumio-markus volumio[1182]: info: [1748673104488] [80s80s] adding url: http://streams.90s90s.de/pop/mp3-192/volumio
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio"
May 31 08:31:44 volumio-markus volumio[1182]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split')
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 153ms
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: options
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: options
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:44 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand status took 82 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" took 73 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand status took 52 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand status took 43 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand play
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:44 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:44 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::pushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 192ms
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 228ms
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 236ms
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:44 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:44 volumio-markus volumio[1182]: info:
May 31 08:31:44 volumio-markus volumio[1182]: ---------------------------- MPD announces system playlist update
May 31 08:31:44 volumio-markus volumio[1182]: info: Ignoring MPD Status Update
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 191ms
May 31 08:31:44 volumio-markus volumio[1182]: info: sendMpdCommand play took 143 milliseconds
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 5ms
May 31 08:31:44 volumio-markus volumio[1182]: info: ------------------------------ 2ms
May 31 08:31:44 volumio-markus volumio[1182]: info: [1748673104753] [80s80s] Pushing the next song state: Madonna - Deeper and Deeper
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:44 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:44 volumio-markus volumio[1182]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music115/v4/d4/cb/6e/d4cb6ec4-da95-c5d4-5b41-29c7d281bc9e/source/600x600bb.jpg","name":"Madonna - Deeper and Deeper","title":"Deeper and Deeper","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"287","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2}
May 31 08:31:44 volumio-markus volumio[1182]: verbose: CURRENT POSITION 0
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::syncState stateService play
May 31 08:31:44 volumio-markus volumio[1182]: info: CoreStateMachine::syncState currentStatus stop
May 31 08:31:44 volumio-markus volumio[1182]: info: [1748673104770] [80s80s] PlayNextTrack API delay: 30
May 31 08:31:44 volumio-markus volumio[1182]: info: [1748673104829] [80s80s] Setting timer to: 287000 milliseconds.
May 31 08:31:45 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:45 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 1
May 31 08:31:45 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:45 volumio-markus mpd[1453]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied
May 31 08:31:45 volumio-markus mpd[1453]: player: problems opening audio device while playing "http://streams.90s90s.de/pop/mp3-192/volumio"
May 31 08:31:45 volumio-markus volumio[1182]: info:
May 31 08:31:45 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:45 volumio-markus volumio[1182]: info:
May 31 08:31:45 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:45 volumio-markus volumio[1182]: info:
May 31 08:31:45 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:45 volumio-markus volumio[1182]: info:
May 31 08:31:45 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand status took 28 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:45 volumio-markus volumio[1182]: info:
May 31 08:31:45 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:45 volumio-markus volumio[1182]: info:
May 31 08:31:45 volumio-markus volumio[1182]: ---------------------------- MPD announces state update: player
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::getState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand status
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand status took 87 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand clearerror
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand status took 87 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 61 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand status took 64 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 63 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand status took 62 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:45 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:45 volumio-markus volumio[1182]: info: ------------------------------ 136ms
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand status took 128 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 72 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand clearerror took 71 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 32 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 31 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 31 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 30 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseState
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:45 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:45 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:45 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:45 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:45 volumio-markus volumio[1182]: info: ------------------------------ 172ms
May 31 08:31:45 volumio-markus volumio[1182]: info: ------------------------------ 171ms
May 31 08:31:45 volumio-markus volumio[1182]: info: ------------------------------ 171ms
May 31 08:31:45 volumio-markus volumio[1182]: info: ------------------------------ 142ms
May 31 08:31:45 volumio-markus volumio[1182]: info: sendMpdCommand playlistinfo took 12 milliseconds
May 31 08:31:45 volumio-markus volumio[1182]: verbose: ControllerMpd::parseTrackInfo
May 31 08:31:45 volumio-markus volumio[1182]: info: ControllerMpd::pushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CoreCommandRouter::servicePushState
May 31 08:31:45 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:45 volumio-markus volumio[1182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 31 08:31:45 volumio-markus volumio[1182]: info: ------------------------------ 142ms
May 31 08:31:47 volumio-markus volumio[1182]: info: CoreCommandRouter::volumioPlay
May 31 08:31:47 volumio-markus volumio[1182]: info: CoreStateMachine::play index undefined
May 31 08:31:47 volumio-markus volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined
May 31 08:31:47 volumio-markus volumio[1182]: info: CorePlayQueue::getTrack 0
May 31 08:31:56 volumio-markus volumio[1182]: Searching all installed plugins
May 31 08:31:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 31 08:31:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: , search
May 31 08:31:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: mpd , search
May 31 08:31:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search
May 31 08:31:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: last_100 , search
May 31 08:31:56 volumio-markus volumio[1182]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100
May 31 08:31:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: webradio , search
May 31 08:31:56 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search
May 31 08:31:56 volumio-markus volumio[1182]: info: [youtube2] InnertubeLoader: creating Innertube instance...
May 31 08:31:57 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search
May 31 08:31:57 volumio-markus volumio[1182]: info: CoreCommandRouter::executeOnPlugin: spop , search
May 31 08:31:57 volumio-markus mpd[1453]: exception: No database
May 31 08:31:57 volumio-markus mpd[1453]: exception: No database
May 31 08:31:57 volumio-markus mpd[1453]: exception: No database
May 31 08:31:57 volumio-markus volumio[1182]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database
May 31 08:31:57 volumio-markus volumio[1182]: Use XMLElement.setAttribute instead
May 31 08:31:57 volumio-markus volumio[1182]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 31 08:31:57 volumio-markus volumio[1182]: TypeError: children[i].attr(...).value is not a function
May 31 08:31:57 volumio-markus volumio[1182]: at /volumio/app/plugins/music_service/webradio/index.js:956:46
May 31 08:31:57 volumio-markus volumio[1182]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
May 31 08:31:57 volumio-markus volumio[1182]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 31 08:31:59 volumio-markus sudo[2286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-31 08:30'
May 31 08:31:59 volumio-markus sudo[2286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
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="1da895bfdbb6eb90d965df32a25a3568df7c84ed"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed May 28 19:10:18 UTC 2025"
VOLUMIO_VERSION="4.003"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="1bd40b20372a0e1ffe6ebc6d3df45570"