-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2025-04-25 14:44:56 CEST. -- Apr 25 14:43:07 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 14:43:07 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 14:43:07 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 14:43:07 volumio-integro volumio[3289]: error: Plugin music_service qobuz failed to complete 'onStart' in a timely fashion Apr 25 14:43:07 volumio-integro volumio[3289]: info: ------------------------------------------- Apr 25 14:43:07 volumio-integro volumio[3289]: info: ----- MyVolumio plugins startup ---- Apr 25 14:43:07 volumio-integro volumio[3289]: info: ------------------------------------------- Apr 25 14:43:07 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 25 14:43:27 volumio-integro volumio[3289]: error: MyVolumio Plugin failed to start in a timely fashion Apr 25 14:43:27 volumio-integro volumio[3289]: [Metrics] CommandRouter: 77s 160.29ms Apr 25 14:43:27 volumio-integro volumio[3289]: info: CoreCommandRouter::volumiosetStartupVolume Apr 25 14:43:27 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:27 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 14:43:27 volumio-integro volumio[3289]: info: CoreCommandRouter::Close All Modals sent Apr 25 14:43:27 volumio-integro volumio[3289]: info: CoreCommandRouter::Close All Modals sent Apr 25 14:43:27 volumio-integro kernel: aml_tdm_open Apr 25 14:43:27 volumio-integro kernel: Not init audio effects Apr 25 14:43:27 volumio-integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 25 14:43:27 volumio-integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 25 14:43:27 volumio-integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 25 14:43:27 volumio-integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 25 14:43:27 volumio-integro kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc050bf5618, id(1), clksel(1) Apr 25 14:43:27 volumio-integro kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 25 14:43:27 volumio-integro kernel: dump_pcm_setting(ffffffc050bf5618) Apr 25 14:43:27 volumio-integro kernel: pcm_mode(1) Apr 25 14:43:27 volumio-integro kernel: sysclk(11289600) Apr 25 14:43:27 volumio-integro kernel: sysclk_bclk_ratio(4) Apr 25 14:43:27 volumio-integro kernel: bclk(2822400) Apr 25 14:43:27 volumio-integro kernel: bclk_lrclk_ratio(64) Apr 25 14:43:27 volumio-integro kernel: lrclk(44100) Apr 25 14:43:27 volumio-integro kernel: tx_mask(0x3) Apr 25 14:43:27 volumio-integro kernel: rx_mask(0x3) Apr 25 14:43:27 volumio-integro kernel: slots(2) Apr 25 14:43:27 volumio-integro kernel: slot_width(32) Apr 25 14:43:27 volumio-integro kernel: lane_mask_in(0x2) Apr 25 14:43:27 volumio-integro kernel: lane_mask_out(0x1) Apr 25 14:43:27 volumio-integro kernel: lane_oe_mask_in(0x0) Apr 25 14:43:27 volumio-integro kernel: lane_oe_mask_out(0x0) Apr 25 14:43:27 volumio-integro kernel: lane_lb_mask_in(0x0) Apr 25 14:43:27 volumio-integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 25 14:43:27 volumio-integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 25 14:43:27 volumio-integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 25 14:43:27 volumio-integro kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 25 14:43:27 volumio-integro kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 25 14:43:27 volumio-integro kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 25 14:43:27 volumio-integro kernel: aml_tdm_prepare(), reset fddr Apr 25 14:43:27 volumio-integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Apr 25 14:43:27 volumio-integro kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 25 14:43:27 volumio-integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 25 14:43:27 volumio-integro kernel: tdm playback mute: 0, lane_cnt = 8 Apr 25 14:43:27 volumio-integro kernel: aml_tdm_prepare(), reset fddr Apr 25 14:43:27 volumio-integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Apr 25 14:43:27 volumio-integro kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 25 14:43:27 volumio-integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 25 14:43:27 volumio-integro kernel: tdm playback mute: 0, lane_cnt = 8 Apr 25 14:43:27 volumio-integro kernel: asoc-aml-card auge_sound: tdm playback enable Apr 25 14:43:27 volumio-integro kernel: spdif_a is set to enable Apr 25 14:43:28 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 25 14:43:28 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 14:43:28 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 25 14:43:31 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 25 14:43:32 volumio-integro kernel: asoc-aml-card auge_sound: tdm playback stop Apr 25 14:43:32 volumio-integro kernel: spdif_a is set to disable Apr 25 14:43:32 volumio-integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 25 14:43:32 volumio-integro kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 25 14:43:32 volumio-integro kernel: tdm playback mute: 1, lane_cnt = 8 Apr 25 14:43:32 volumio-integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 25 14:43:34 volumio-integro volumio[3289]: info: BOOT COMPLETED Apr 25 14:43:35 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Date not found in response Apr 25 14:43:35 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Retrying in 5 seconds... Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 14:43:36 volumio-integro volumio[3289]: info: Not Reporting Auto name since its the default one Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 25 14:43:36 volumio-integro sudo[4330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 25 14:43:36 volumio-integro sudo[4330]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 14:43:36 volumio-integro sudo[4330]: pam_unix(sudo:session): session closed for user root Apr 25 14:43:36 volumio-integro sudo[4332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 25 14:43:36 volumio-integro sudo[4332]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 14:43:36 volumio-integro systemd[1]: Started RAAT DAEMON. Apr 25 14:43:36 volumio-integro sudo[4332]: pam_unix(sudo:session): session closed for user root Apr 25 14:43:36 volumio-integro volumio[3289]: info: Raat Daemon started successfully Apr 25 14:43:36 volumio-integro kernel: aml_tdm_open Apr 25 14:43:36 volumio-integro kernel: Not init audio effects Apr 25 14:43:36 volumio-integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 25 14:43:36 volumio-integro kernel: tdm playback mute: 1, lane_cnt = 8 Apr 25 14:43:36 volumio-integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 25 14:43:36 volumio-integro kernel: aml_tdm_open Apr 25 14:43:36 volumio-integro kernel: Not init audio effects Apr 25 14:43:36 volumio-integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 25 14:43:36 volumio-integro kernel: tdm playback mute: 1, lane_cnt = 8 Apr 25 14:43:36 volumio-integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 25 14:43:36 volumio-integro volumio[3289]: info: Executing endpoint restartRAATSocket Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 25 14:43:36 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:43:36 volumio-integro volumio[3289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 25 14:43:36 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:43:36 volumio-integro volumio[3289]: [VRCP] RAAT stopped playing Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:43:36 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:43:36 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:43:36 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:43:36 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:43:36 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:43:36 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:43:36 volumio-integro volumio[3289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received raat Apr 25 14:43:37 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:43:39 volumio-integro volumio[3289]: info: RAAT: Requesting Headphone Status Apr 25 14:43:39 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 25 14:43:39 volumio-integro volumio[3289]: info: MCU Signalled Headphone Mode Disabled Apr 25 14:43:39 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 25 14:43:39 volumio-integro volumio[3289]: info: Reporting Headphone State: false Apr 25 14:43:39 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:39 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:43:39 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:43:40 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Fetching time from Volumio... Apr 25 14:43:40 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Date not found in response Apr 25 14:43:40 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Retrying in 5 seconds... Apr 25 14:43:43 volumio-integro volumio[3289]: warn: QOBUZ login failed: No access token received Apr 25 14:43:43 volumio-integro volumio[3289]: info: Stopping AccessToken refresher cron for QOBUZ Apr 25 14:43:43 volumio-integro volumio[3289]: info: AccessToken refresher cron started for QOBUZ Apr 25 14:43:43 volumio-integro volumio[3289]: info: Adding QOBUZ REST API Endpoints Apr 25 14:43:43 volumio-integro volumio[3289]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 25 14:43:45 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Fetching time from Volumio... Apr 25 14:43:45 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Date not found in response Apr 25 14:43:45 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Retrying in 5 seconds... Apr 25 14:43:50 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Fetching time from Volumio... Apr 25 14:43:51 volumio-integro volumiologrotate[2834]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 25 14:43:51 volumio-integro volumiologrotate[2834]: ls: cannot access 'INTEGRO': No such file or directory Apr 25 14:43:53 volumio-integro volumio[3289]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=mp1&variante=integro&version=3.799&uuid=4545d86057e655c308c8e9e2cd9ee583" http://updates.volumio.org/downloader-v1/track-device Apr 25 14:43:53 volumio-integro volumio[3289]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 25 14:43:53 volumio-integro volumio[3289]: Dload Upload Total Spent Left Speed Apr 25 14:43:53 volumio-integro volumio[3289]: [5.2K blob data] Apr 25 14:43:53 volumio-integro volumio[3289]: curl: (7) Failed to connect to updates.volumio.org port 80: No route to host Apr 25 14:43:53 volumio-integro volumio[3289]: retrying in 5 seconds, trial 0 Apr 25 14:43:53 volumio-integro volumio[3289]: info: Volumio Calling Home Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 25 14:43:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 25 14:43:57 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 25 14:43:57 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 25 14:43:57 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 14:43:57 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 14:43:57 volumio-integro volumio[3289]: info: Starting MyVolumio Remote Streaming Endpoints Apr 25 14:43:57 volumio-integro volumio[3289]: info: MyVolumio login type: Token Apr 25 14:43:57 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 25 14:43:57 volumio-integro volumio[3289]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Apr 25 14:44:12 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 14:44:12 volumio-integro volumio[3289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 25 14:44:12 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:12 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:17 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:44:17 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 14:44:17 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 25 14:44:17 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 25 14:44:17 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 25 14:44:17 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 14:44:17 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 14:44:18 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:44:18 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:44:18 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:18 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:44:18 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:18 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:18 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:44:18 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:44:18 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:18 volumio-integro volumio[3289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received raat Apr 25 14:44:18 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:44:25 volumio-integro volumio[3289]: info: [VRCP] preparing for control Apr 25 14:44:25 volumio-integro volumio[3289]: info: RAAT Acquiring Control Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioStop Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreStateMachine::stop Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 14:44:25 volumio-integro volumio[3289]: info: RAAT Control Acquired Apr 25 14:44:25 volumio-integro volumio[3289]: info: [VRCP] prepared for control Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 14:44:25 volumio-integro volumio[3289]: info: [VRCP] acknowledged control Apr 25 14:44:25 volumio-integro volumio[3289]: [VRCP] RAAT stopped playing Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:44:25 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:25 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:44:25 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:44:25 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:25 volumio-integro volumio[3289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received raat Apr 25 14:44:25 volumio-integro volumio[3289]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Apr 25 14:44:25 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:44:27 volumio-integro volumio[3289]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 25 14:44:27 volumio-integro volumio[3289]: info: Retrying Login Due to Network Error Apr 25 14:44:28 volumio-integro volumio[3289]: info: Executing endpoint metavolumio Apr 25 14:44:28 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 14:44:35 volumio-integro volumio[3289]: error: Failed request for metavolumio API Apr 25 14:44:37 volumio-integro volumio[3289]: info: MyVolumio login type: Token Apr 25 14:44:46 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:44:46 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:44:46 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:44:46 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:46 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:44:46 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:44:46 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:46 volumio-integro volumio[3289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received raat Apr 25 14:44:46 volumio-integro volumio[3289]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Apr 25 14:44:46 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:44:48 volumio-integro volumio[3289]: info: Executing endpoint metavolumio Apr 25 14:44:48 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 14:44:48 volumio-integro volumio[3289]: error: Failed request for metavolumio API Apr 25 14:44:51 volumio-integro volumiologrotate[2834]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 25 14:44:51 volumio-integro volumiologrotate[2834]: ls: cannot access 'INTEGRO': No such file or directory Apr 25 14:44:51 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:44:51 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:44:51 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:44:51 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:51 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:44:51 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:44:51 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:51 volumio-integro volumio[3289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received raat Apr 25 14:44:51 volumio-integro volumio[3289]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Apr 25 14:44:51 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:44:53 volumio-integro volumio[3289]: error: MyVolumio Custom Token format not valid, refreshing it Apr 25 14:44:53 volumio-integro volumio[3289]: info: [VRCP] already in control, acknowledged Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:53 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:44:53 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:44:53 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:53 volumio-integro volumio[3289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received raat Apr 25 14:44:53 volumio-integro volumio[3289]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Apr 25 14:44:53 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:44:53 volumio-integro kernel: aml_tdm_open Apr 25 14:44:53 volumio-integro kernel: Not init audio effects Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:44:53 volumio-integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 25 14:44:53 volumio-integro kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Apr 25 14:44:53 volumio-integro kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc050bf5618, id(1), clksel(1) Apr 25 14:44:53 volumio-integro kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 25 14:44:53 volumio-integro kernel: dump_pcm_setting(ffffffc050bf5618) Apr 25 14:44:53 volumio-integro kernel: pcm_mode(1) Apr 25 14:44:53 volumio-integro kernel: sysclk(12288000) Apr 25 14:44:53 volumio-integro kernel: sysclk_bclk_ratio(4) Apr 25 14:44:53 volumio-integro kernel: bclk(3072000) Apr 25 14:44:53 volumio-integro kernel: bclk_lrclk_ratio(64) Apr 25 14:44:53 volumio-integro kernel: lrclk(48000) Apr 25 14:44:53 volumio-integro kernel: tx_mask(0x3) Apr 25 14:44:53 volumio-integro kernel: rx_mask(0x3) Apr 25 14:44:53 volumio-integro kernel: slots(2) Apr 25 14:44:53 volumio-integro kernel: slot_width(32) Apr 25 14:44:53 volumio-integro kernel: lane_mask_in(0x2) Apr 25 14:44:53 volumio-integro kernel: lane_mask_out(0x1) Apr 25 14:44:53 volumio-integro kernel: lane_oe_mask_in(0x0) Apr 25 14:44:53 volumio-integro kernel: lane_oe_mask_out(0x0) Apr 25 14:44:53 volumio-integro kernel: lane_lb_mask_in(0x0) Apr 25 14:44:53 volumio-integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 25 14:44:53 volumio-integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 25 14:44:53 volumio-integro kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Apr 25 14:44:53 volumio-integro kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 25 14:44:53 volumio-integro kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 25 14:44:53 volumio-integro kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 25 14:44:53 volumio-integro kernel: aml_tdm_prepare(), reset fddr Apr 25 14:44:53 volumio-integro kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 25 14:44:53 volumio-integro kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Apr 25 14:44:53 volumio-integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 25 14:44:53 volumio-integro kernel: tdm playback mute: 0, lane_cnt = 8 Apr 25 14:44:53 volumio-integro kernel: aml_tdm_prepare(), reset fddr Apr 25 14:44:53 volumio-integro kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 25 14:44:53 volumio-integro kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Apr 25 14:44:53 volumio-integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 25 14:44:53 volumio-integro kernel: tdm playback mute: 0, lane_cnt = 8 Apr 25 14:44:53 volumio-integro kernel: asoc-aml-card auge_sound: tdm playback enable Apr 25 14:44:53 volumio-integro kernel: spdif_a is set to enable Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:53 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:44:53 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:44:53 volumio-integro volumio[3289]: info: CorePlayQueue::getTrack 0 Apr 25 14:44:53 volumio-integro volumio[3289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received raat Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:44:53 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:53 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:44:53 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:44:53 volumio-integro volumio[3289]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Apr 25 14:44:53 volumio-integro volumio[3289]: info: Signalling Playback active due to playback status change Apr 25 14:44:53 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:44:53 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:44:53 volumio-integro volumio[3289]: info: MCU Signalled Playback Active Apr 25 14:44:54 volumio-integro volumio[3289]: info: MyVolumio login type: Token Apr 25 14:44:54 volumio-integro volumio[3289]: info: CoreCommandRouter::servicePushState Apr 25 14:44:54 volumio-integro volumio[3289]: info: CoreStateMachine::pushState Apr 25 14:44:54 volumio-integro volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 14:44:54 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioPushState Apr 25 14:44:54 volumio-integro volumio[3289]: info: CoreCommandRouter::volumioGetState Apr 25 14:44:54 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output update for this device Apr 25 14:44:54 volumio-integro volumio[3289]: info: MRS: Pushing multiroomSync output Apr 25 14:44:54 volumio-integro volumio[3289]: info: Signalling Playback active due to playback status change Apr 25 14:44:54 volumio-integro volumio[3289]: info: Updating RAAT Signal Path Apr 25 14:44:54 volumio-integro volumio[3289]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 25 14:44:55 volumio-integro volumio-time-update[2816]: volumio-time-update-util: Setting system time to: 2025-04-25 14:44:55 Apr 25 14:44:55 volumio-integro sudo[4627]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-04-25 14:44:55 Apr 25 14:44:55 volumio-integro sudo[4627]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 14:44:55 volumio-integro volumio[3289]: info: MyVolumio token set successfully Apr 25 14:44:55 volumio-integro volumio[3289]: info: MYVOLUMIO: Adding device Apr 25 14:44:55 volumio-integro volumio[3289]: info: MYVOLUMIO: Evaluating Server Apr 25 14:44:55 volumio-integro dbus-daemon[2817]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.21' (uid=0 pid=4629 comm="timedatectl set-time 2025-04-25 14:44:55 " label="kernel") Apr 25 14:44:55 volumio-integro systemd[1]: Starting Time & Date Service... Apr 25 14:44:55 volumio-integro dbus-daemon[2817]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 25 14:44:55 volumio-integro systemd[1]: Started Time & Date Service. Apr 25 14:44:55 volumio-integro systemd-timedated[4630]: Changed local time to Fri Apr 25 14:44:55 2025 Apr 25 14:44:55 volumio-integro systemd[1]: Started Volumio Time Update Utility. Apr 25 14:44:55 volumio-integro volumio-time-update[2816]: volumio-time-update-util: System time updated successfully. Apr 25 14:44:55 volumio-integro sudo[4627]: pam_unix(sudo:session): session closed for user root Apr 25 14:44:55 volumio-integro systemd[1]: Reached target Multi-User System. Apr 25 14:44:55 volumio-integro systemd[1]: Reached target Graphical Interface. Apr 25 14:44:55 volumio-integro systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 25 14:44:55 volumio-integro systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 25 14:44:55 volumio-integro systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 25 14:44:55 volumio-integro systemd[1]: Startup finished in 12.827s (kernel) + 3min 9.477s (userspace) = 3min 22.304s. Apr 25 14:44:55 volumio-integro volumio[3289]: info: MyVolumio Plan changed: superstar Apr 25 14:44:55 volumio-integro volumio[3289]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 25 14:44:55 volumio-integro volumio[3289]: info: Adding audio output: Apr 25 14:44:55 volumio-integro volumio[3289]: info: MYVOLUMIO: Adding device Apr 25 14:44:55 volumio-integro volumio[3289]: info: MYVOLUMIO: Evaluating Server Apr 25 14:44:55 volumio-integro volumio[3289]: error: Failed to ping endpoint eu2.myvolumio.org : unknown error Apr 25 14:44:55 volumio-integro volumio[3289]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 14:44:55 volumio-integro volumio[3289]: Error: Unable to resolve or reject the same promise twice Apr 25 14:44:55 volumio-integro volumio[3289]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 25 14:44:55 volumio-integro volumio[3289]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Apr 25 14:44:55 volumio-integro volumio[3289]: at Socket.emit (events.js:400:28) Apr 25 14:44:55 volumio-integro volumio[3289]: at addChunk (internal/streams/readable.js:293:12) Apr 25 14:44:55 volumio-integro volumio[3289]: at readableAddChunk (internal/streams/readable.js:267:9) Apr 25 14:44:55 volumio-integro volumio[3289]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 25 14:44:55 volumio-integro volumio[3289]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 25 14:44:55 volumio-integro volumio[3289]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 14:44:56 volumio-integro sudo[4666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-25 14:43 Apr 25 14:44:56 volumio-integro sudo[4666]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="integro" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 08:21:01 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Integro" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Integro" VOLUMIO_HASH="e4bf773a7a29410391c374b4329f7a82"