-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2024-11-24 11:58:01 UTC. -- Nov 24 11:57:31 volumio volumio[832]: info: [yt-cast-receiver] (YouTube) Sender disconnected: iPhone (IJ) Nov 24 11:57:31 volumio volumio[832]: info: [yt-cast-receiver] Player.reset() Nov 24 11:57:31 volumio volumio[832]: info: [ytcr] ***** Sender disconnected ***** Nov 24 11:57:31 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 11:57:31 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Nov 24 11:57:31 volumio volumio[832]: info: CoreCommandRouter::servicePushState Nov 24 11:57:31 volumio volumio[832]: info: CoreStateMachine::pushState Nov 24 11:57:31 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Nov 24 11:57:31 volumio volumio[832]: info: CoreCommandRouter::servicePushState Nov 24 11:57:31 volumio volumio[832]: info: CoreStateMachine::pushState Nov 24 11:57:31 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Nov 24 11:57:31 volumio volumio[832]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Nov 24 11:57:31 volumio volumio[832]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Nov 24 11:57:31 volumio volumio[832]: info: [yt-cast-receiver] (YouTube) Sender connected: iPhone (IJ) (user: Igor) Nov 24 11:57:31 volumio volumio[832]: info: [ytcr] ***** Sender connected ***** Nov 24 11:57:31 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 11:57:34 volumio volumio[832]: warn: [yt-cast-receiver] DefaultPlaylistRequestHandler failed to mark video Qh8QwVYOSVU as watched: Nov 24 11:57:34 volumio volumio[832]: (Error) Request to https://www.youtube.com/youtubei/vnull/player?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Nov 24 11:57:34 volumio volumio[832]: Error: Request to https://www.youtube.com/youtubei/vnull/player?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Nov 24 11:57:34 volumio volumio[832]: at HTTPClient. (/data/plugins/music_service/ytcr/node_modules/youtubei.js/bundle/node.cjs:20188:13) Nov 24 11:57:34 volumio volumio[832]: at Generator.next () Nov 24 11:57:34 volumio volumio[832]: at fulfilled (/data/plugins/music_service/ytcr/node_modules/youtubei.js/bundle/node.cjs:20067:24) Nov 24 11:57:34 volumio volumio[832]: at runMicrotasks () Nov 24 11:57:34 volumio volumio[832]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 24 11:57:34 volumio volumio[832]: error: [yt-cast-receiver] Failed to get autoplay video for video Id: Qh8QwVYOSVU Nov 24 11:57:34 volumio volumio[832]: (Error) Request to https://www.youtube.com/youtubei/vnull/next?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Nov 24 11:57:34 volumio volumio[832]: Error: Request to https://www.youtube.com/youtubei/vnull/next?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Nov 24 11:57:34 volumio volumio[832]: at HTTPClient. (/data/plugins/music_service/ytcr/node_modules/youtubei.js/bundle/node.cjs:20188:13) Nov 24 11:57:34 volumio volumio[832]: at Generator.next () Nov 24 11:57:34 volumio volumio[832]: at fulfilled (/data/plugins/music_service/ytcr/node_modules/youtubei.js/bundle/node.cjs:20067:24) Nov 24 11:57:34 volumio volumio[832]: at runMicrotasks () Nov 24 11:57:34 volumio volumio[832]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 24 11:57:34 volumio volumio[832]: info: [yt-cast-receiver] Player.play(): Qh8QwVYOSVU @ 0s Nov 24 11:57:34 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Nov 24 11:57:34 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Nov 24 11:57:35 volumio volumio[832]: error: [ytcr] Error in VideoLoader.getInfo(Qh8QwVYOSVU): Nov 24 11:57:35 volumio volumio[832]: (Error) Request to https://www.youtube.com/youtubei/vnull/next?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Nov 24 11:57:35 volumio volumio[832]: Error: Request to https://www.youtube.com/youtubei/vnull/next?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Nov 24 11:57:35 volumio volumio[832]: at HTTPClient. (/data/plugins/music_service/ytcr/node_modules/volumio-youtubei.js/bundle/node.cjs:20188:13) Nov 24 11:57:35 volumio volumio[832]: at Generator.next () Nov 24 11:57:35 volumio volumio[832]: at fulfilled (/data/plugins/music_service/ytcr/node_modules/volumio-youtubei.js/bundle/node.cjs:20067:24) Nov 24 11:57:35 volumio volumio[832]: at runMicrotasks () Nov 24 11:57:35 volumio volumio[832]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 24 11:57:35 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Nov 24 11:57:35 volumio volumio[832]: info: CoreCommandRouter::servicePushState Nov 24 11:57:35 volumio volumio[832]: info: CoreStateMachine::pushState Nov 24 11:57:35 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Nov 24 11:57:35 volumio volumio[832]: info: Ignoring MPD Status Update Nov 24 11:57:35 volumio volumio[832]: info: Ignoring MPD Status Update Nov 24 11:57:35 volumio volumio[832]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Nov 24 11:57:41 volumio volumio[832]: error: [ytcr] Failed to obtain manual pairing code: timeout. Nov 24 11:57:48 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 11:57:48 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 11:57:57 volumio volumio[832]: info: Enabling plugin fusiondsp Nov 24 11:57:57 volumio volumio[832]: info: Loading plugin "fusiondsp"... Nov 24 11:57:58 volumio volumio[832]: info: Preparing to generate the ALSA configuration file Nov 24 11:57:58 volumio volumio[832]: info: Asound.conf file unchanged, so no further update is needed Nov 24 11:57:58 volumio volumio[832]: info: Output device has changed, restarting MPD Nov 24 11:57:58 volumio sudo[2511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 11:57:58 volumio sudo[2511]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 11:57:58 volumio sudo[2511]: pam_unix(sudo:session): session closed for user root Nov 24 11:57:58 volumio volumio[832]: info: Output device has changed, restarting Shairport Sync Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 11:57:58 volumio sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 11:57:58 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 11:57:58 volumio systemd[1]: Stopping Music Player Daemon... Nov 24 11:57:58 volumio volumio[832]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 11:57:58 volumio volumio[832]: info: PLUGIN START: fusiondsp Nov 24 11:57:58 volumio volumio[832]: info: Loading i18n strings for locale en Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: FusionDsp - mixtype--------------------- Hardware Nov 24 11:57:58 volumio volumio[832]: info: Preparing to generate the ALSA configuration file Nov 24 11:57:58 volumio volumio[832]: info: Done. Nov 24 11:57:58 volumio volumio[832]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 24 11:57:58 volumio volumio[832]: info: Reading ALSA contributions from plugins. Nov 24 11:57:58 volumio volumio[832]: info: MPD Permissions set Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::servicePushState Nov 24 11:57:58 volumio volumio[832]: info: CoreStateMachine::pushState Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Nov 24 11:57:58 volumio volumio[832]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio systemd[1]: mpd.service: Succeeded. Nov 24 11:57:58 volumio systemd[1]: Stopped Music Player Daemon. Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:58 volumio volumio[832]: info: Starting Shairport Sync Nov 24 11:57:58 volumio systemd[1]: Starting Music Player Daemon... Nov 24 11:57:59 volumio volumio[832]: info: Asound.conf file written Nov 24 11:57:59 volumio sudo[2525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 11:57:59 volumio sudo[2525]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 11:57:59 volumio sudo[2528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 24 11:57:59 volumio sudo[2528]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 11:57:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 11:57:59 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 24 11:57:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 11:57:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 11:57:59 volumio sudo[2528]: pam_unix(sudo:session): session closed for user root Nov 24 11:57:59 volumio sudo[2525]: pam_unix(sudo:session): session closed for user root Nov 24 11:57:59 volumio volumio[832]: info: Output device has changed, restarting MPD Nov 24 11:57:59 volumio sudo[2539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 11:57:59 volumio sudo[2539]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 11:57:59 volumio volumio[832]: info: Output device has changed, restarting Shairport Sync Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 11:57:59 volumio sudo[2539]: pam_unix(sudo:session): session closed for user root Nov 24 11:57:59 volumio sudo[2542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 11:57:59 volumio sudo[2542]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 11:57:59 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 24 11:57:59 volumio systemd[1]: mpd.service: Succeeded. Nov 24 11:57:59 volumio systemd[1]: Stopped Music Player Daemon. Nov 24 11:57:59 volumio systemd[1]: Starting Music Player Daemon... Nov 24 11:57:59 volumio volumio[832]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 11:57:59 volumio volumio[832]: info: MPD Permissions set Nov 24 11:57:59 volumio volumio[832]: info: Shairport-Sync Started Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:57:59 volumio volumio[832]: info: Starting Shairport Sync Nov 24 11:57:59 volumio sudo[2553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 11:57:59 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 11:57:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 11:57:59 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 24 11:57:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 11:57:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 11:57:59 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Nov 24 11:57:59 volumio volumio[832]: info: Shairport-Sync Started Nov 24 11:58:00 volumio volumio[832]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 24 11:58:00 volumio volumio[832]: info: camilladsp service started and running in background, instance 1 Nov 24 11:58:00 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 11:58:00 volumio volumio[832]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Nov 24 11:58:00 volumio volumio[832]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 24 11:58:00 volumio volumio[832]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Nov 24 11:58:00 volumio volumio[832]: info: FusionDsp loaded Nov 24 11:58:00 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 11:58:00 volumio sudo[2570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 24 11:58:00 volumio volumio[832]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 24 11:58:00 volumio sudo[2570]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 11:58:00 volumio volumio[832]: info: Adding Signal Path Element [object Object] Nov 24 11:58:00 volumio volumio[832]: info: Adding fusiondspeq DSP Signal Path Element Nov 24 11:58:00 volumio volumio[832]: info: FusionDsp - ---- installed callbackRead Nov 24 11:58:00 volumio volumio[832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 11:58:01 volumio volumio[832]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Nov 24 11:58:01 volumio volumio[832]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 24 11:58:01 volumio volumio[832]: at onErrorNT (internal/child_process.js:465:16) Nov 24 11:58:01 volumio volumio[832]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 24 11:58:01 volumio volumio[832]: errno: -2, Nov 24 11:58:01 volumio volumio[832]: code: 'ENOENT', Nov 24 11:58:01 volumio volumio[832]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 24 11:58:01 volumio volumio[832]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 24 11:58:01 volumio volumio[832]: spawnargs: [ Nov 24 11:58:01 volumio volumio[832]: '-p', Nov 24 11:58:01 volumio volumio[832]: 9876, Nov 24 11:58:01 volumio volumio[832]: '-o', Nov 24 11:58:01 volumio volumio[832]: '/tmp/camilladsp.log', Nov 24 11:58:01 volumio volumio[832]: '-l', Nov 24 11:58:01 volumio volumio[832]: 'warn', Nov 24 11:58:01 volumio volumio[832]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 24 11:58:01 volumio volumio[832]: ] Nov 24 11:58:01 volumio volumio[832]: } Nov 24 11:58:01 volumio volumio[832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 11:58:01 volumio sudo[2570]: pam_unix(sudo:session): session closed for user root Nov 24 11:58:01 volumio mpd[2559]: Nov 24 11:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 11:58:01 volumio sudo[2583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 11:57 Nov 24 11:58:01 volumio sudo[2583]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"