-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-08-18 11:40:43 CEST. -- Aug 18 11:39:00 volumio volumio[977]: info: Starting Streaming Service Transparent Proxy Aug 18 11:39:00 volumio volumio[977]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 18 11:39:00 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 18 11:39:00 volumio volumio[977]: info: Streaming services startup Aug 18 11:39:00 volumio volumio[977]: info: Starting Streaming Daemon Aug 18 11:39:00 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 18 11:39:00 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:39:00 volumio volumio[977]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 18 11:39:00 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Aug 18 11:39:00 volumio volumio[977]: error: Cannot start Volumio Streaming Daemon Aug 18 11:39:00 volumio volumio[977]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 18 11:39:00 volumio volumio[977]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 18 11:39:00 volumio volumio[977]: error: MyVolumio Custom Token format not valid, refreshing it Aug 18 11:39:01 volumio volumio[977]: info: MyVolumio login type: Token Aug 18 11:39:01 volumio volumio[977]: STREAMING PROXY: Starting server on port 3245 Aug 18 11:39:01 volumio volumio[977]: Node JS runtime: 14 Aug 18 11:39:02 volumio volumio[977]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 18 11:39:03 volumio volumio[977]: info: MyVolumio token set successfully Aug 18 11:39:03 volumio volumio[977]: info: MYVOLUMIO: Adding device Aug 18 11:39:03 volumio volumio[977]: info: MYVOLUMIO: Evaluating Server Aug 18 11:39:03 volumio volumio[977]: info: MyVolumio status changed Aug 18 11:39:03 volumio volumio[977]: info: Streaming services startup Aug 18 11:39:03 volumio volumio[977]: info: Starting Streaming Daemon Aug 18 11:39:03 volumio volumio[977]: info: Removing browser output: myVolumio user plan is not superstar Aug 18 11:39:03 volumio volumio[977]: info: Removing audio output: Aug 18 11:39:03 volumio volumio[977]: info: Stoppping Tunnel 1 Aug 18 11:39:03 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 18 11:39:03 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:39:03 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 18 11:39:03 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:39:03 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Aug 18 11:39:03 volumio volumio[977]: error: Cannot start Volumio Streaming Daemon Aug 18 11:39:03 volumio volumio[977]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 18 11:39:03 volumio volumio[977]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 18 11:39:03 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Aug 18 11:39:03 volumio volumio[977]: info: Remote SSH Stopped Aug 18 11:39:03 volumio volumio[977]: info: Setting Geolocation for MyVolumio to eu3 Aug 18 11:39:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:04 volumio volumio[977]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 18 11:39:04 volumio volumio[977]: info: Updating MyVolumio device info Aug 18 11:39:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:05 volumio volumio[977]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 18 11:39:05 volumio volumio[1221]: ...........................................................................................................++++ Aug 18 11:39:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:39:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:39:05 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:39:05 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:05 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:39:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:39:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:39:05 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:39:05 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:05 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:39:05 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.0.142:3000 from 192.168.0.133 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Aug 18 11:39:06 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:06 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 18 11:39:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 18 11:39:08 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 11:39:08 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:39:08 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root Aug 18 11:39:08 volumio sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 11:39:08 volumio sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:39:08 volumio sudo[1279]: pam_unix(sudo:session): session closed for user root Aug 18 11:39:08 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.0.142 from 192.168.0.133 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Aug 18 11:39:08 volumio volumio[977]: info: MYVOLUMIO: Adding device Aug 18 11:39:08 volumio volumio[977]: info: MYVOLUMIO: Evaluating Server Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:08 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 11:39:08 volumio volumio[977]: info: Received Get System Info Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:39:08 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:08 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:08 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:08 volumio volumio[977]: info: Listing playlists Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:39:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 18 11:39:09 volumio volumio[977]: info: Setting Geolocation for MyVolumio to eu7 Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:09 volumio sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 11:39:09 volumio sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:39:09 volumio sudo[1298]: pam_unix(sudo:session): session closed for user root Aug 18 11:39:09 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 11:39:09 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:39:09 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Aug 18 11:39:09 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.0.142 from 192.168.0.133 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:09 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 11:39:09 volumio volumio[977]: info: Received Get System Info Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:39:09 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:09 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:09 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:09 volumio volumio[977]: info: Listing playlists Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:39:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 18 11:39:09 volumio volumio[977]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 18 11:39:10 volumio volumio[977]: info: Updating MyVolumio device info Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:10 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:10 volumio volumio[977]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 11:39:10 volumio volumio[977]: info: Received Get System Info Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:39:10 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:10 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:39:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 18 11:39:12 volumio volumio[1221]: ..............................................++++ Aug 18 11:39:12 volumio volumio[1221]: e is 65537 (0x010001) Aug 18 11:39:12 volumio volumio[1221]: writing RSA key Aug 18 11:39:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 11:39:12 volumio volumio[977]: info: Received Get System Info Aug 18 11:39:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:39:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:39:12 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:39:12 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:39:14 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:39:14 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 18 11:39:14 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:14 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:14 volumio volumio[977]: info: CoreCommandRouter::volumioGetQueue Aug 18 11:39:14 volumio volumio[977]: info: CoreStateMachine::getQueue Aug 18 11:39:14 volumio volumio[977]: info: CorePlayQueue::getQueue Aug 18 11:39:15 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:39:15 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:15 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 11:39:15 volumio volumio-remote-updater[673]: No test mode Aug 18 11:39:15 volumio volumio-remote-updater[673]: No alpha test mode Aug 18 11:39:15 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 18 11:39:15 volumio volumio[977]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 18 11:39:15 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:39:19 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 18 11:39:20 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 18 11:39:20 volumio volumio[977]: info: Preload queue cleared Aug 18 11:39:20 volumio volumio[977]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 18 11:39:20 volumio volumio[977]: info: Completed starting MyVolumio Plugin Aug 18 11:39:20 volumio volumio[977]: info: BOOT COMPLETED Aug 18 11:39:20 volumio volumio[977]: [Metrics] CommandRouter: 43s 306.89ms Aug 18 11:39:20 volumio volumio[977]: info: CoreCommandRouter::volumiosetStartupVolume Aug 18 11:39:20 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:39:20 volumio volumio[977]: info: VolumeController:: Setting startup Volume 30 Aug 18 11:39:20 volumio volumio[977]: info: VolumeController::SetAlsaVolume30 Aug 18 11:39:20 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:39:20 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Aug 18 11:39:20 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Aug 18 11:39:20 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:20 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:20 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:39:20 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:20 volumio volumio[977]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 18 11:39:20 volumio volumio[977]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 18 11:39:20 volumio volumio[977]: info: camilladsp stopping service pid 1193... Aug 18 11:39:20 volumio volumio[977]: info: camilladsp service terminated, instance 1 Aug 18 11:39:20 volumio volumio[977]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 18 11:39:20 volumio volumio[977]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Aug 18 11:39:20 volumio volumio[977]: 2+0 records in Aug 18 11:39:20 volumio volumio[977]: 2+0 records out Aug 18 11:39:20 volumio volumio[977]: 65536 bytes (66 kB, 64 KiB) copied, 0.000402813 s, 163 MB/s Aug 18 11:39:20 volumio volumio[977]: info: camilladsp spawned new process with pid 1339, instance 1, run: true Aug 18 11:39:20 volumio volumio[977]: info: camilladsp service started and running in background, instance 1 Aug 18 11:39:21 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 18 11:39:21 volumio volumio[977]: info: Preload queue cleared Aug 18 11:39:21 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 18 11:39:21 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 11:39:21 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 18 11:39:25 volumio volumio[977]: 0+0 records in Aug 18 11:39:25 volumio volumio[977]: 0+0 records out Aug 18 11:39:25 volumio volumio[977]: 0 bytes copied, 0.000141563 s, 0.0 kB/s Aug 18 11:39:25 volumio volumio[977]: info: camilladsp spawned new process with pid 1386, instance 1, run: true Aug 18 11:39:25 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Aug 18 11:39:25 volumio volumio[977]: info: CoreStateMachine::play index undefined Aug 18 11:39:25 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:39:25 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:25 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Aug 18 11:39:25 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:25 volumio volumio[977]: info: [1723973965927] ControllerWebradio::clearAddPlayTrack Aug 18 11:39:25 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand stop Aug 18 11:39:25 volumio volumio[977]: info: sendMpdCommand stop took 5 milliseconds Aug 18 11:39:25 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand clear Aug 18 11:39:25 volumio volumio[977]: info: sendMpdCommand clear took 1 milliseconds Aug 18 11:39:25 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand load "http://broadcast.infomaniak.ch/mistralfm-high.mp3" Aug 18 11:39:25 volumio volumio[977]: info: Aug 18 11:39:25 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:25 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:25 volumio volumio[977]: info: Aug 18 11:39:25 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:25 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:25 volumio volumio[977]: info: Aug 18 11:39:25 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:25 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:25 volumio volumio[977]: info: Aug 18 11:39:25 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:25 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand add "http://broadcast.infomaniak.ch/mistralfm-high.mp3" Aug 18 11:39:26 volumio volumio[977]: error: updateQueue error: null Aug 18 11:39:26 volumio volumio[977]: error: updateQueue error: null Aug 18 11:39:26 volumio volumio[977]: error: updateQueue error: null Aug 18 11:39:26 volumio volumio[977]: error: updateQueue error: null Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:26 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 738ms Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 734ms Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 731ms Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 730ms Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand add "http://broadcast.infomaniak.ch/mistralfm-high.mp3" took 11 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand play Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:26 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:26 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:26 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 25ms Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand play took 19 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 18ms Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 15ms Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 13ms Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 18 11:39:26 volumio volumio[977]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 18 11:39:26 volumio volumio[977]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:26 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 40 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 37 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 28 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 24 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 22 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 20 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:26 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:26 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces system playlist update Aug 18 11:39:26 volumio volumio[977]: info: Ignoring MPD Status Update Aug 18 11:39:26 volumio volumio[977]: info: Aug 18 11:39:26 volumio volumio[977]: ---------------------------- MPD announces state update: player Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::getState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 45 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 32 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 39ms Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 29 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 21ms Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 19 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 18ms Aug 18 11:39:26 volumio volumio[977]: info: sendMpdCommand status took 16 milliseconds Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 15ms Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:26 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:26 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:26 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","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":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:26 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:26 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:26 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","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":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:26 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:26 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:26 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:26 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","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":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:26 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:26 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:26 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:26 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","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":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:26 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:26 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:26 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:26 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","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":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:26 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:26 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:26 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:26 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","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":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:26 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:26 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:26 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:26 volumio volumio[977]: info: ------------------------------ 166ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 238ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 236ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 225ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 222ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 221ms Aug 18 11:39:27 volumio volumio[977]: info: sendMpdCommand status took 181 milliseconds Aug 18 11:39:27 volumio volumio[977]: info: sendMpdCommand playlistinfo took 168 milliseconds Aug 18 11:39:27 volumio volumio[977]: info: sendMpdCommand playlistinfo took 167 milliseconds Aug 18 11:39:27 volumio volumio[977]: info: sendMpdCommand playlistinfo took 166 milliseconds Aug 18 11:39:27 volumio volumio[977]: info: sendMpdCommand playlistinfo took 161 milliseconds Aug 18 11:39:27 volumio volumio[977]: info: sendMpdCommand playlistinfo took 161 milliseconds Aug 18 11:39:27 volumio volumio[977]: verbose: ControllerMpd::parseState Aug 18 11:39:27 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 11:39:27 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:27 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:27 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:27 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:27 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:27 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:27 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:27 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:27 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:27 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:27 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:27 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:27 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:27 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:27 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:27 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:27 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:27 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:27 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:27 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:27 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:27 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:27 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:27 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:27 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:27 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 347ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 341ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 341ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 320ms Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 318ms Aug 18 11:39:27 volumio volumio[977]: info: sendMpdCommand playlistinfo took 181 milliseconds Aug 18 11:39:27 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Aug 18 11:39:27 volumio volumio[977]: info: ControllerMpd::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::servicePushState Aug 18 11:39:27 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Aug 18 11:39:27 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Timmy Trumpet - Sun Comes Up (Feat Sam Feldt & Ekko & Joe Taylor)","artist":"Mistral FM Toulon","album":null,"uri":"http://broadcast.infomaniak.ch/mistralfm-high.mp3","trackType":"mp3"} Aug 18 11:39:27 volumio volumio[977]: verbose: CURRENT POSITION 0 Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Aug 18 11:39:27 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:27 volumio volumio[977]: info: ------------------------------ 394ms Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:39:27 volumio volumio[977]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 18 11:39:27 volumio volumio[977]: info: Received Get System Version Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:39:27 volumio volumio[977]: info: Received Get System Info Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:39:27 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:39:27 volumio volumio[977]: info: FusionDsp - eq1: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 25 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 3 Aug 18 11:39:27 volumio volumio[977]: eq2: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 40 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 4 Aug 18 11:39:27 volumio volumio[977]: eq3: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 63 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 3 Aug 18 11:39:27 volumio volumio[977]: eq4: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 100 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 3 Aug 18 11:39:27 volumio volumio[977]: eq5: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 160 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 5 Aug 18 11:39:27 volumio volumio[977]: eq6: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 250 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 4.5 Aug 18 11:39:27 volumio volumio[977]: eq7: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 400 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 2.5 Aug 18 11:39:27 volumio volumio[977]: eq8: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 630 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 0 Aug 18 11:39:27 volumio volumio[977]: eq9: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 1000 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 1.5 Aug 18 11:39:27 volumio volumio[977]: eq10: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 1600 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 1.5 Aug 18 11:39:27 volumio volumio[977]: eq11: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 2500 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 3.5 Aug 18 11:39:27 volumio volumio[977]: eq12: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 4000 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 4 Aug 18 11:39:27 volumio volumio[977]: eq13: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 6300 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 4 Aug 18 11:39:27 volumio volumio[977]: eq14: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 10000 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 5 Aug 18 11:39:27 volumio volumio[977]: eq15: Aug 18 11:39:27 volumio volumio[977]: type: Biquad Aug 18 11:39:27 volumio volumio[977]: parameters: Aug 18 11:39:27 volumio volumio[977]: type: Peaking Aug 18 11:39:27 volumio volumio[977]: freq: 16000 Aug 18 11:39:27 volumio volumio[977]: q: 1.85 Aug 18 11:39:27 volumio volumio[977]: gain: 6.5 Aug 18 11:39:27 volumio volumio[977]: info: FusionDsp - {"Reload":{"result":"Ok"}} Aug 18 11:39:28 volumio ntpd[794]: Soliciting pool server 45.13.105.44 Aug 18 11:39:28 volumio ntpd[794]: Soliciting pool server 188.165.49.6 Aug 18 11:39:29 volumio ntpd[794]: Soliciting pool server 162.159.200.1 Aug 18 11:39:30 volumio ntpd[794]: Soliciting pool server 5.39.80.51 Aug 18 11:39:30 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:39:30 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:39:30 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:39:30 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:39:30 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:39:31 volumio ntpd[794]: Soliciting pool server 176.137.36.37 Aug 18 11:39:31 volumio ntpd[794]: Soliciting pool server 129.250.35.251 Aug 18 11:39:31 volumio ntpd[794]: Soliciting pool server 82.64.84.116 Aug 18 11:39:32 volumio ntpd[794]: Soliciting pool server 82.64.81.218 Aug 18 11:39:32 volumio ntpd[794]: Soliciting pool server 194.57.169.1 Aug 18 11:39:33 volumio ntpd[794]: Soliciting pool server 82.64.247.11 Aug 18 11:39:33 volumio ntpd[794]: Soliciting pool server 217.182.137.208 Aug 18 11:39:33 volumio ntpd[794]: Soliciting pool server 212.85.158.10 Aug 18 11:39:34 volumio ntpd[794]: Soliciting pool server 94.23.215.121 Aug 18 11:39:34 volumio ntpd[794]: Soliciting pool server 2001:41d0:2:c837::123 Aug 18 11:39:34 volumio ntpd[794]: Soliciting pool server 54.36.61.42 Aug 18 11:39:35 volumio volumio[977]: info: VolumeController::SetAlsaVolume39 Aug 18 11:39:35 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:39:35 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:35 volumio volumio[977]: info: VolumeController::SetAlsaVolume40 Aug 18 11:39:35 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:39:35 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:35 volumio volumio[977]: info: VolumeController::SetAlsaVolume41 Aug 18 11:39:35 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:39:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:39:35 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:39:35 volumio ntpd[794]: Soliciting pool server 82.67.71.253 Aug 18 11:40:00 volumio ntpd[794]: receive: Unexpected origin timestamp 0xea6c3fd7.844da970 does not match aorg 0000000000.00000000 from server@176.137.36.37 xmt 0xea6c3ff0.1c4a8f92 Aug 18 11:40:00 volumio ntpd[794]: receive: Unexpected origin timestamp 0xea6c3fd7.844fd347 does not match aorg 0000000000.00000000 from server@5.39.80.51 xmt 0xea6c3ff0.1ce42a0f Aug 18 11:40:00 volumio ntpd[794]: receive: Unexpected origin timestamp 0xea6c3fd7.8442b381 does not match aorg 0000000000.00000000 from server@82.64.247.11 xmt 0xea6c3ff0.1c05ddae Aug 18 11:40:00 volumio ntpd[794]: receive: Unexpected origin timestamp 0xea6c3fd7.843895c7 does not match aorg 0000000000.00000000 from server@217.182.137.208 xmt 0xea6c3ff0.1cc94acc Aug 18 11:40:00 volumio ntpd[794]: receive: Unexpected origin timestamp 0xea6c3fd7.84457620 does not match aorg 0000000000.00000000 from server@82.64.81.218 xmt 0xea6c3ff0.1e479fe5 Aug 18 11:40:00 volumio systemd[1]: Starting Daily apt download activities... Aug 18 11:40:00 volumio volumio[977]: info: VolumeController::SetAlsaVolume42 Aug 18 11:40:00 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:40:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:40:00 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:40:01 volumio ntpd[794]: Soliciting pool server 51.15.191.239 Aug 18 11:40:03 volumio systemd[1]: apt-daily.service: Succeeded. Aug 18 11:40:03 volumio systemd[1]: Started Daily apt download activities. Aug 18 11:40:04 volumio volumio[977]: info: VolumeController::SetAlsaVolume49 Aug 18 11:40:04 volumio volumio[977]: info: CoreStateMachine::pushState Aug 18 11:40:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:40:04 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 11:40:11 volumio volumio[977]: info: Getting Alsa Cards List without I2S DAC Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 18 11:40:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 18 11:40:13 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:40:18 volumio volumio[977]: info: Retrieving Cloud Streaming UI Aug 18 11:40:18 volumio volumio[977]: info: Getting Tidal Cloud Configuration Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:40:18 volumio volumio[977]: info: Getting Qobuz Cloud Configuration Aug 18 11:40:18 volumio volumio[977]: info: Asking plugin for UI Config Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:40:18 volumio volumio[977]: info: Getting Spotify Cloud Configuration Aug 18 11:40:18 volumio volumio[977]: info: Asking plugin for UI Config Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:40:18 volumio volumio[977]: info: Saving Spotify Acccount Aug 18 11:40:18 volumio volumio[977]: info: Got Tidal Cloud Configuration Aug 18 11:40:18 volumio volumio[977]: info: Got it Aug 18 11:40:18 volumio volumio[977]: info: Got it Aug 18 11:40:18 volumio volumio[977]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:40:18 volumio volumio[977]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 18 11:40:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 18 11:40:21 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:40:25 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:40:25 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:40:25 volumio volumio[977]: info: Discovery: Getting this device information Aug 18 11:40:25 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Aug 18 11:40:25 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:40:30 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:40:31 volumio volumio[977]: info: Preload queue cleared Aug 18 11:40:32 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:40:32 volumio volumio[977]: info: Preload queue cleared Aug 18 11:40:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:40:35 volumio volumio[977]: info: Preload queue cleared Aug 18 11:40:42 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:40:42 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 11:40:42 volumio volumio[977]: TypeError: Cannot read property 'length' of undefined Aug 18 11:40:42 volumio volumio[977]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 18 11:40:42 volumio volumio[977]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 18 11:40:42 volumio volumio[977]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 18 11:40:42 volumio volumio[977]: at Parser.emit (events.js:315:20) Aug 18 11:40:42 volumio volumio[977]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 18 11:40:42 volumio volumio[977]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 18 11:40:42 volumio volumio[977]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 18 11:40:42 volumio volumio[977]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 18 11:40:42 volumio volumio[977]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 18 11:40:42 volumio volumio[977]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 18 11:40:42 volumio volumio[977]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 18 11:40:42 volumio volumio[977]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 18 11:40:42 volumio volumio[977]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 18 11:40:42 volumio volumio[977]: at IncomingMessage.emit (events.js:327:22) Aug 18 11:40:42 volumio volumio[977]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 18 11:40:42 volumio volumio[977]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 18 11:40:42 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 11:40:43 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 11:39 Aug 18 11:40:43 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"