Jun 02 16:01:00 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:01:01 rpi4-argon CRON[2534]: pam_unix(cron:session): session opened for user volumio(uid=1000) by (uid=0) Jun 02 16:01:01 rpi4-argon CRON[2535]: (volumio) CMD (sudo /bin/bash /home/volumio/scripts/date_time.sh) Jun 02 16:01:01 rpi4-argon sudo[2536]: pam_unix(sudo:auth): conversation failed Jun 02 16:01:01 rpi4-argon sudo[2536]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jun 02 16:01:03 rpi4-argon exim[2539]: 2025-06-02 16:01:03 1uM5ip-0000ex-0T <= volumio@localhost U=volumio P=local S=539 Jun 02 16:01:03 rpi4-argon exim[2539]: 2025-06-02 16:01:03 1uM5ip-0000ex-0T Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=112 egid=117 Jun 02 16:01:03 rpi4-argon exim[2539]: exim: could not open panic log - aborting: see message(s) above Jun 02 16:01:03 rpi4-argon cron[2540]: 2025-06-02 16:01:03 1uM5ip-0000ey-0V Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=112 egid=117 Jun 02 16:01:03 rpi4-argon cron[2540]: 2025-06-02 16:01:03 1uM5ip-0000ey-0V <= volumio@localhost U=volumio P=local S=775 Jun 02 16:01:03 rpi4-argon cron[2540]: 2025-06-02 16:01:03 1uM5ip-0000ey-0V Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=112 egid=117 Jun 02 16:01:03 rpi4-argon cron[2540]: exim: could not open panic log - aborting: see message(s) above Jun 02 16:01:03 rpi4-argon exim[2540]: 2025-06-02 16:01:03 1uM5ip-0000ey-0V <= volumio@localhost U=volumio P=local S=775 Jun 02 16:01:03 rpi4-argon exim[2540]: 2025-06-02 16:01:03 1uM5ip-0000ey-0V Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=112 egid=117 Jun 02 16:01:03 rpi4-argon exim[2540]: exim: could not open panic log - aborting: see message(s) above Jun 02 16:01:03 rpi4-argon CRON[2534]: (volumio) MAIL (mailed 164 bytes of output but got status 0x0001 from MTA ) Jun 02 16:01:03 rpi4-argon CRON[2534]: pam_unix(cron:session): session closed for user volumio Jun 02 16:01:03 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 16:01:03 rpi4-argon volumio[1436]: info: Received Get System Info Jun 02 16:01:03 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 16:01:03 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 16:01:03 rpi4-argon volumio[1436]: info: Discovery: Getting this device information Jun 02 16:01:03 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioGetState Jun 02 16:01:03 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 16:01:05 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 16:01:05 rpi4-argon volumio[1436]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 02 16:01:05 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 02 16:01:05 rpi4-argon volumio[1436]: info: Received Get System Version Jun 02 16:01:05 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 16:01:05 rpi4-argon volumio[1436]: info: Received Get System Info Jun 02 16:01:05 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 16:01:05 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 16:01:05 rpi4-argon volumio[1436]: info: Discovery: Getting this device information Jun 02 16:01:05 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioGetState Jun 02 16:01:05 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 16:01:07 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:01:08 rpi4-argon systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 02 16:01:13 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:01:15 rpi4-argon kernel: rc rc0: two consecutive events of type pulse Jun 02 16:01:20 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:01:25 rpi4-argon go-librespot[1816]: time="2025-06-02T16:01:25+02:00" level=trace msg="sent dealer ping" Jun 02 16:01:25 rpi4-argon go-librespot[1816]: time="2025-06-02T16:01:25+02:00" level=trace msg="received dealer pong" Jun 02 16:01:27 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:01:34 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:01:41 rpi4-argon volumio[1436]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Jun 02 16:01:42 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioGetState Jun 02 16:01:42 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioNext Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::next Jun 02 16:01:48 rpi4-argon volumio[1436]: info: [1748872908407] ControllerTidal::next Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::next Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::stop Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::stPlaybackTimer Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::updateTrackBlock Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrackBlock Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::pushState Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrack 0 Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioPushState Jun 02 16:01:48 rpi4-argon volumio[1436]: error: Help! Some callbacks for volumioPushState are crashing! Jun 02 16:01:48 rpi4-argon volumio[1436]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::serviceStop Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrack 0 Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreCommandRouter::serviceStop Jun 02 16:01:48 rpi4-argon volumio[1436]: info: [1748872908472] ControllerTidal::stop Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 02 16:01:48 rpi4-argon volumio[1436]: info: ControllerMpd::stop Jun 02 16:01:48 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 16:01:48 rpi4-argon volumio[1436]: info: Jun 02 16:01:48 rpi4-argon volumio[1436]: ---------------------------- MPD announces state update: player Jun 02 16:01:48 rpi4-argon volumio[1436]: info: sendMpdCommand stop took 136 milliseconds Jun 02 16:01:48 rpi4-argon volumio[1436]: info: ControllerMpd::getState Jun 02 16:01:48 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand status Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::play index undefined Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrack 1 Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::startPlaybackTimer Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrack 1 Jun 02 16:01:48 rpi4-argon volumio[1436]: info: [1748872908614] ControllerTidal::clearAddPlayTrack Jun 02 16:01:48 rpi4-argon volumio[1436]: info: Getting stream with soundQuality HI_RES Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::updateTrackBlock Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrackBlock Jun 02 16:01:48 rpi4-argon volumio[1436]: info: sendMpdCommand status took 13 milliseconds Jun 02 16:01:48 rpi4-argon volumio[1436]: verbose: ControllerMpd::parseState Jun 02 16:01:48 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 16:01:48 rpi4-argon volumio[1436]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 02 16:01:48 rpi4-argon volumio[1436]: verbose: ControllerMpd::parseTrackInfo Jun 02 16:01:48 rpi4-argon volumio[1436]: info: ControllerMpd::pushState Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreCommandRouter::servicePushState Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreStateMachine::pushState Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrack 1 Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioPushState Jun 02 16:01:48 rpi4-argon volumio[1436]: error: Help! Some callbacks for volumioPushState are crashing! Jun 02 16:01:48 rpi4-argon volumio[1436]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Jun 02 16:01:48 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrack 1 Jun 02 16:01:48 rpi4-argon volumio[1436]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 02 16:01:48 rpi4-argon volumio[1436]: info: ------------------------------ 118ms Jun 02 16:01:48 rpi4-argon volumio[1436]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 02 16:01:49 rpi4-argon volumio[1436]: info: getStreamUrl took 526 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 16:01:49 rpi4-argon volumio[1436]: info: sendMpdCommand stop took 1 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand clear Jun 02 16:01:49 rpi4-argon volumio[1436]: info: Jun 02 16:01:49 rpi4-argon volumio[1436]: ---------------------------- MPD announces system playlist update Jun 02 16:01:49 rpi4-argon volumio[1436]: info: Ignoring MPD Status Update Jun 02 16:01:49 rpi4-argon volumio[1436]: info: sendMpdCommand clear took 1 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand add "http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic5NGFlMzZlYTk2MzllMDcxYjk0NzczNjljOWRkZDY0NV82Mi5tcDQ/0.flac?Expires=1748876510&Signature=XD89dXtuZXy6RInGXqUUplKoRoB5l-0uu4JfcXtrd3kpQbw4SSxWNysAWhdxEX2SG5B2k6wfW04wjcIPoR2baX7o7GUVruyB7ChALLvMaN2X1el6wHIFs00lWBY8FNsdh6zK1YDLmuPL8JSYW-GkviU9vlv96fVH4YXuP3eKmEgfi1qMBe0OUrRNwMcAqlMes5AZiARGZjVEZ8iwVe8fbwKSSuyXGnZHf7UzPe4cWdtK8p-Gv-~J~5MlW3OgeJwwZEXl6xlalGoDjXaSCcBDyrMxCckNdmbWua07ClCbLJy~VyeKrpfhL1nYDfztjehSwM3XaCLgva~XWUu8Tag74A__&Key-Pair-Id=K14LZCZ9QUI4JL" Jun 02 16:01:49 rpi4-argon volumio[1436]: error: updateQueue error: null Jun 02 16:01:49 rpi4-argon volumio[1436]: info: ------------------------------ 2ms Jun 02 16:01:49 rpi4-argon volumio[1436]: info: sendMpdCommand add "http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic5NGFlMzZlYTk2MzllMDcxYjk0NzczNjljOWRkZDY0NV82Mi5tcDQ/0.flac?Expires=1748876510&Signature=XD89dXtuZXy6RInGXqUUplKoRoB5l-0uu4JfcXtrd3kpQbw4SSxWNysAWhdxEX2SG5B2k6wfW04wjcIPoR2baX7o7GUVruyB7ChALLvMaN2X1el6wHIFs00lWBY8FNsdh6zK1YDLmuPL8JSYW-GkviU9vlv96fVH4YXuP3eKmEgfi1qMBe0OUrRNwMcAqlMes5AZiARGZjVEZ8iwVe8fbwKSSuyXGnZHf7UzPe4cWdtK8p-Gv-~J~5MlW3OgeJwwZEXl6xlalGoDjXaSCcBDyrMxCckNdmbWua07ClCbLJy~VyeKrpfhL1nYDfztjehSwM3XaCLgva~XWUu8Tag74A__&Key-Pair-Id=K14LZCZ9QUI4JL" took 1 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand play Jun 02 16:01:49 rpi4-argon volumio[1436]: info: Jun 02 16:01:49 rpi4-argon volumio[1436]: ---------------------------- MPD announces system playlist update Jun 02 16:01:49 rpi4-argon volumio[1436]: info: Ignoring MPD Status Update Jun 02 16:01:49 rpi4-argon volumio[1436]: info: Jun 02 16:01:49 rpi4-argon volumio[1436]: ---------------------------- MPD announces state update: player Jun 02 16:01:49 rpi4-argon volumio[1436]: info: Jun 02 16:01:49 rpi4-argon volumio[1436]: ---------------------------- MPD announces state update: player Jun 02 16:01:49 rpi4-argon volumio[1436]: info: sendMpdCommand play took 55 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: info: ControllerMpd::getState Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand status Jun 02 16:01:49 rpi4-argon volumio[1436]: info: ------------------------------ 55ms Jun 02 16:01:49 rpi4-argon volumio[1436]: info: ControllerMpd::getState Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand status Jun 02 16:01:49 rpi4-argon volumio[1436]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jun 02 16:01:49 rpi4-argon volumio[1436]: info: FusionDsp - ---- read samplerate from file: 48000 Jun 02 16:01:49 rpi4-argon volumio[1436]: info: camilladsp stopping service pid 2229... Jun 02 16:01:49 rpi4-argon kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 1 Jun 02 16:01:49 rpi4-argon kernel: xhci_hcd 0000:01:00.0: @0000000428d187a0 00000000 00000000 0e000000 03028001 Jun 02 16:01:49 rpi4-argon volumio[1436]: grep: /proc/2229/cmdline: binary file matches Jun 02 16:01:49 rpi4-argon volumio[1436]: grep: /proc/2229/cmdline: binary file matches Jun 02 16:01:49 rpi4-argon volumio[1436]: info: camilladsp service terminated, instance 1 Jun 02 16:01:49 rpi4-argon volumio[1436]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 02 16:01:49 rpi4-argon volumio[1436]: info: FusionDsp - Effects disabled Jun 02 16:01:49 rpi4-argon volumio[1436]: info: camilladsp service started and running in background, instance 1 Jun 02 16:01:49 rpi4-argon volumio[1436]: info: sendMpdCommand status took 290 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: info: sendMpdCommand status took 290 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::parseState Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::parseState Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 16:01:49 rpi4-argon volumio[1436]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::parseTrackInfo Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: ControllerMpd::parseTrackInfo Jun 02 16:01:49 rpi4-argon volumio[1436]: info: ControllerMpd::pushState Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreCommandRouter::servicePushState Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrack 1 Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?Expires=1748876510&Signature=XD89dXtuZXy6RInGXqUUplKoRoB5l-0uu4JfcXtrd3kpQbw4SSxWNysAWhdxEX2SG5B2k6wfW04wjcIPoR2baX7o7GUVruyB7ChALLvMaN2X1el6wHIFs00lWBY8FNsdh6zK1YDLmuPL8JSYW-GkviU9vlv96fVH4YXuP3eKmEgfi1qMBe0OUrRNwMcAqlMes5AZiARGZjVEZ8iwVe8fbwKSSuyXGnZHf7UzPe4cWdtK8p-Gv-~J~5MlW3OgeJwwZEXl6xlalGoDjXaSCcBDyrMxCckNdmbWua07ClCbLJy~VyeKrpfhL1nYDfztjehSwM3XaCLgva~XWUu8Tag74A__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic5NGFlMzZlYTk2MzllMDcxYjk0NzczNjljOWRkZDY0NV82Mi5tcDQ/0.flac?Expires=1748876510&Signature=XD89dXtuZXy6RInGXqUUplKoRoB5l-0uu4JfcXtrd3kpQbw4SSxWNysAWhdxEX2SG5B2k6wfW04wjcIPoR2baX7o7GUVruyB7ChALLvMaN2X1el6wHIFs00lWBY8FNsdh6zK1YDLmuPL8JSYW-GkviU9vlv96fVH4YXuP3eKmEgfi1qMBe0OUrRNwMcAqlMes5AZiARGZjVEZ8iwVe8fbwKSSuyXGnZHf7UzPe4cWdtK8p-Gv-~J~5MlW3OgeJwwZEXl6xlalGoDjXaSCcBDyrMxCckNdmbWua07ClCbLJy~VyeKrpfhL1nYDfztjehSwM3XaCLgva~XWUu8Tag74A__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: CURRENT POSITION 1 Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreStateMachine::syncState stateService play Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreStateMachine::syncState currentStatus stop Jun 02 16:01:49 rpi4-argon volumio[1436]: info: ControllerMpd::pushState Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreCommandRouter::servicePushState Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CorePlayQueue::getTrack 1 Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?Expires=1748876510&Signature=XD89dXtuZXy6RInGXqUUplKoRoB5l-0uu4JfcXtrd3kpQbw4SSxWNysAWhdxEX2SG5B2k6wfW04wjcIPoR2baX7o7GUVruyB7ChALLvMaN2X1el6wHIFs00lWBY8FNsdh6zK1YDLmuPL8JSYW-GkviU9vlv96fVH4YXuP3eKmEgfi1qMBe0OUrRNwMcAqlMes5AZiARGZjVEZ8iwVe8fbwKSSuyXGnZHf7UzPe4cWdtK8p-Gv-~J~5MlW3OgeJwwZEXl6xlalGoDjXaSCcBDyrMxCckNdmbWua07ClCbLJy~VyeKrpfhL1nYDfztjehSwM3XaCLgva~XWUu8Tag74A__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic5NGFlMzZlYTk2MzllMDcxYjk0NzczNjljOWRkZDY0NV82Mi5tcDQ/0.flac?Expires=1748876510&Signature=XD89dXtuZXy6RInGXqUUplKoRoB5l-0uu4JfcXtrd3kpQbw4SSxWNysAWhdxEX2SG5B2k6wfW04wjcIPoR2baX7o7GUVruyB7ChALLvMaN2X1el6wHIFs00lWBY8FNsdh6zK1YDLmuPL8JSYW-GkviU9vlv96fVH4YXuP3eKmEgfi1qMBe0OUrRNwMcAqlMes5AZiARGZjVEZ8iwVe8fbwKSSuyXGnZHf7UzPe4cWdtK8p-Gv-~J~5MlW3OgeJwwZEXl6xlalGoDjXaSCcBDyrMxCckNdmbWua07ClCbLJy~VyeKrpfhL1nYDfztjehSwM3XaCLgva~XWUu8Tag74A__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Jun 02 16:01:49 rpi4-argon volumio[1436]: verbose: CURRENT POSITION 1 Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreStateMachine::syncState stateService play Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreStateMachine::syncState currentStatus play Jun 02 16:01:49 rpi4-argon volumio[1436]: info: Received an update from plugin. extracting info from payload Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreStateMachine::pushState Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioPushState Jun 02 16:01:49 rpi4-argon volumio[1436]: error: Help! Some callbacks for volumioPushState are crashing! Jun 02 16:01:49 rpi4-argon volumio[1436]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreStateMachine::pushState Jun 02 16:01:49 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioPushState Jun 02 16:01:49 rpi4-argon volumio[1436]: error: Help! Some callbacks for volumioPushState are crashing! Jun 02 16:01:49 rpi4-argon volumio[1436]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Jun 02 16:01:49 rpi4-argon volumio[1436]: info: ------------------------------ 360ms Jun 02 16:01:49 rpi4-argon volumio[1436]: info: ------------------------------ 547ms Jun 02 16:01:50 rpi4-argon volumio[1436]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 02 16:01:50 rpi4-argon volumio[1436]: info: Executing endpoint metavolumio Jun 02 16:01:50 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 16:01:50 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:01:50 rpi4-argon volumio[1436]: info: Executing endpoint metavolumio Jun 02 16:01:50 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 16:01:50 rpi4-argon volumio[1436]: info: Executing endpoint metavolumio Jun 02 16:01:50 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 16:01:55 rpi4-argon go-librespot[1816]: time="2025-06-02T16:01:55+02:00" level=trace msg="sent dealer ping" Jun 02 16:01:55 rpi4-argon go-librespot[1816]: time="2025-06-02T16:01:55+02:00" level=trace msg="received dealer pong" Jun 02 16:01:57 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:02:01 rpi4-argon CRON[2653]: pam_unix(cron:session): session opened for user volumio(uid=1000) by (uid=0) Jun 02 16:02:01 rpi4-argon CRON[2654]: (volumio) CMD (sudo /bin/bash /home/volumio/scripts/date_time.sh) Jun 02 16:02:01 rpi4-argon sudo[2655]: pam_unix(sudo:auth): conversation failed Jun 02 16:02:01 rpi4-argon sudo[2655]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jun 02 16:02:01 rpi4-argon cron[2659]: 2025-06-02 16:02:01 1uM5jl-0000gt-1P Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=112 egid=117 Jun 02 16:02:01 rpi4-argon cron[2659]: 2025-06-02 16:02:01 1uM5jl-0000gt-1P <= volumio@localhost U=volumio P=local S=775 Jun 02 16:02:01 rpi4-argon cron[2659]: 2025-06-02 16:02:01 1uM5jl-0000gt-1P Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=112 egid=117 Jun 02 16:02:01 rpi4-argon cron[2659]: exim: could not open panic log - aborting: see message(s) above Jun 02 16:02:01 rpi4-argon exim[2659]: 2025-06-02 16:02:01 1uM5jl-0000gt-1P <= volumio@localhost U=volumio P=local S=775 Jun 02 16:02:01 rpi4-argon exim[2659]: 2025-06-02 16:02:01 1uM5jl-0000gt-1P Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=112 egid=117 Jun 02 16:02:01 rpi4-argon exim[2659]: exim: could not open panic log - aborting: see message(s) above Jun 02 16:02:01 rpi4-argon CRON[2653]: (volumio) MAIL (mailed 164 bytes of output but got status 0x0001 from MTA ) Jun 02 16:02:01 rpi4-argon CRON[2653]: pam_unix(cron:session): session closed for user volumio Jun 02 16:02:01 rpi4-argon exim[2658]: 2025-06-02 16:02:01 1uM5jl-0000gs-1Q <= volumio@localhost U=volumio P=local S=539 Jun 02 16:02:01 rpi4-argon exim[2658]: 2025-06-02 16:02:01 1uM5jl-0000gs-1Q Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=112 egid=117 Jun 02 16:02:01 rpi4-argon exim[2658]: exim: could not open panic log - aborting: see message(s) above Jun 02 16:02:01 rpi4-argon systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 02 16:02:01 rpi4-argon dbus-daemon[685]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.41' (uid=0 pid=2661 comm="timedatectl show --property=NTPSynchronized --valu") Jun 02 16:02:01 rpi4-argon systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 02 16:02:01 rpi4-argon dbus-daemon[685]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 02 16:02:01 rpi4-argon systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 02 16:02:01 rpi4-argon setdatetime-helper.sh[2660]: Time is not synchronized. Attempting to sync... Jun 02 16:02:02 rpi4-argon sudo[2669]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 02 Jun 2025 14:02:02 GMT#015' Jun 02 16:02:02 rpi4-argon sudo[2669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 02 16:02:02 rpi4-argon setdatetime-helper.sh[2670]: Mon Jun 2 16:02:02 CEST 2025 Jun 02 16:02:02 rpi4-argon systemd-journald[354]: Time jumped backwards, rotating. Jun 02 16:02:02 rpi4-argon setdatetime-helper.sh[2660]: Time synchronized successfully. Jun 02 16:02:02 rpi4-argon sudo[2669]: pam_unix(sudo:session): session closed for user root Jun 02 16:02:02 rpi4-argon systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 02 16:02:02 rpi4-argon systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 02 16:02:04 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:02:05 rpi4-argon volumio[1436]: info: CoreCommandRouter::volumioGetState Jun 02 16:02:07 rpi4-argon volumio[1436]: info: Executing endpoint metavolumio Jun 02 16:02:07 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 16:02:07 rpi4-argon volumio[1436]: info: Executing endpoint metavolumio Jun 02 16:02:07 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 16:02:07 rpi4-argon volumio[1436]: info: Executing endpoint metavolumio Jun 02 16:02:07 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 16:02:07 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jun 02 16:02:07 rpi4-argon volumio[1436]: info: handleBrowseUri took 212 milliseconds Jun 02 16:02:08 rpi4-argon volumio[1436]: info: Preload queue cleared Jun 02 16:02:08 rpi4-argon volumio[1436]: info: Preload queue cleared Jun 02 16:02:11 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:02:12 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jun 02 16:02:14 rpi4-argon volumio[1436]: info: handleBrowseUri took 2704 milliseconds Jun 02 16:02:14 rpi4-argon volumio[1436]: info: Preload queue cleared Jun 02 16:02:17 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:02:23 rpi4-argon wpa_supplicant[1354]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 16:02:25 rpi4-argon go-librespot[1816]: time="2025-06-02T16:02:25+02:00" level=trace msg="sent dealer ping" Jun 02 16:02:25 rpi4-argon go-librespot[1816]: time="2025-06-02T16:02:25+02:00" level=trace msg="received dealer pong" Jun 02 16:02:26 rpi4-argon volumio[1436]: Searching all installed plugins Jun 02 16:02:26 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 16:02:26 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 02 16:02:26 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 02 16:02:26 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Jun 02 16:02:26 rpi4-argon volumio[1436]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Jun 02 16:02:26 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: spop , search Jun 02 16:02:26 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jun 02 16:02:26 rpi4-argon volumio[1436]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 02 16:02:27 rpi4-argon volumio[1436]: info: searchTIDALUri took 924 milliseconds Jun 02 16:02:27 rpi4-argon volumio[1436]: info: search took 926 milliseconds Jun 02 16:02:28 rpi4-argon volumio[1436]: Use XMLElement.setAttribute instead Jun 02 16:02:28 rpi4-argon volumio[1436]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 16:02:28 rpi4-argon volumio[1436]: TypeError: children[i].attr(...).value is not a function Jun 02 16:02:28 rpi4-argon volumio[1436]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 02 16:02:28 rpi4-argon volumio[1436]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 16:02:28 rpi4-argon volumio[1436]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 16:02:29 rpi4-argon sudo[2717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-02 16:01' Jun 02 16:02:29 rpi4-argon sudo[2717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"