-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Mon 2024-07-08 10:33:57 UTC. -- Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18173, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18180, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18191, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18200, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18207, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18216, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18223, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18230, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18237, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18244, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18251, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:00 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:00 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:00 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18258, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18265, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18272, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18280, instance 1, run: true Jul 08 10:32:00 volumio volumio[976]: info: camilladsp spawned new process with pid 18290, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18297, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18304, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18315, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18324, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18333, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18340, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18347, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18354, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18361, instance 1, run: true Jul 08 10:32:01 volumio kernel: usb 1-1.2: USB disconnect, device number 4 Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18375, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jul 08 10:32:01 volumio volumio[976]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 08 10:32:01 volumio volumio[976]: aplay: pcm_write:2053: write error: File descriptor in bad state Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18384, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:01 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:01 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:01 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:01 volumio volumio[976]: info: No valid Plugin REST Endpoint Jul 08 10:32:01 volumio volumio[976]: info: Jul 08 10:32:01 volumio volumio[976]: ---------------------------- USB Audio Device Detached Jul 08 10:32:01 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jul 08 10:32:01 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 10:32:01 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18394, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18403, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18413, instance 1, run: true Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18420, instance 1, run: true Jul 08 10:32:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 44. Jul 08 10:32:01 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:01 volumio volumio[976]: info: camilladsp spawned new process with pid 18428, instance 1, run: true Jul 08 10:32:02 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18435, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18446, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18455, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18462, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18471, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18478, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18486, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18493, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18502, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18511, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18521, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:02 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:02 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:02 volumio volumio[18427]: Traceback (most recent call last): Jul 08 10:32:02 volumio volumio[18427]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Jul 08 10:32:02 volumio volumio[18427]: from camilladsp import CamillaConnection Jul 08 10:32:02 volumio volumio[18427]: ModuleNotFoundError: No module named 'camilladsp' Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18528, instance 1, run: true Jul 08 10:32:02 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:02 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18535, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18546, instance 1, run: true Jul 08 10:32:02 volumio volumio[976]: info: camilladsp spawned new process with pid 18555, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18562, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18571, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18578, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18585, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18592, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18599, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18609, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18616, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18623, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18630, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18641, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18650, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18657, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18666, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:03 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:03 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:03 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18673, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18680, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18687, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18695, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18705, instance 1, run: true Jul 08 10:32:03 volumio volumio[976]: info: camilladsp spawned new process with pid 18713, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18721, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18728, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18737, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18744, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18751, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18758, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18766, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18774, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18781, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18788, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18796, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18803, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18810, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18817, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18824, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:04 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:04 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:04 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18832, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18846, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18853, instance 1, run: true Jul 08 10:32:04 volumio sudo[1608]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18861, instance 1, run: true Jul 08 10:32:04 volumio sudo[18860]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jul 08 10:32:04 volumio sudo[18860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:04 volumio sudo[18860]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18870, instance 1, run: true Jul 08 10:32:04 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 45. Jul 08 10:32:04 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18877, instance 1, run: true Jul 08 10:32:04 volumio volumio[976]: info: camilladsp spawned new process with pid 18885, instance 1, run: true Jul 08 10:32:05 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18892, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18899, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18906, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18913, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18920, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18927, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18934, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18941, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18948, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18955, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18962, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18969, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18976, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:05 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:05 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:05 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:05 volumio volumio[18878]: Traceback (most recent call last): Jul 08 10:32:05 volumio volumio[18878]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Jul 08 10:32:05 volumio volumio[18878]: from camilladsp import CamillaConnection Jul 08 10:32:05 volumio volumio[18878]: ModuleNotFoundError: No module named 'camilladsp' Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18983, instance 1, run: true Jul 08 10:32:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18990, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 18997, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 19004, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 19011, instance 1, run: true Jul 08 10:32:05 volumio volumio[976]: info: camilladsp spawned new process with pid 19018, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19025, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19032, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19039, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19046, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19053, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19060, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19067, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19074, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19081, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19088, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19095, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19102, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19109, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19116, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19123, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19130, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:06 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:06 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:06 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19137, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19144, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19151, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19158, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19165, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19172, instance 1, run: true Jul 08 10:32:06 volumio volumio[976]: info: camilladsp spawned new process with pid 19179, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19186, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19193, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19200, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19207, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19214, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19221, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19228, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19235, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19242, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19249, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19256, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19263, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19270, instance 1, run: true Jul 08 10:32:07 volumio kernel: usb 1-1.2: new full-speed USB device number 6 using xhci_hcd Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19277, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19284, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19291, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19298, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:07 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:07 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:07 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19305, instance 1, run: true Jul 08 10:32:07 volumio kernel: usb 1-1.2: New USB device found, idVendor=041e, idProduct=3041, bcdDevice= 1.00 Jul 08 10:32:07 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jul 08 10:32:07 volumio kernel: usb 1-1.2: Product: USB SB X-Fi Xtreme Audio Jul 08 10:32:07 volumio kernel: usb 1-1.2: Manufacturer: Creative Technology Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19313, instance 1, run: true Jul 08 10:32:07 volumio kernel: usb 1-1.2: Not enough bandwidth for new device state. Jul 08 10:32:07 volumio kernel: usb 1-1.2: Not enough bandwidth for altsetting 4 Jul 08 10:32:07 volumio kernel: usb 1-1.2: Not enough bandwidth for new device state. Jul 08 10:32:07 volumio kernel: usb 1-1.2: Not enough bandwidth for altsetting 8 Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19320, instance 1, run: true Jul 08 10:32:07 volumio kernel: usb 1-1.2: Not enough bandwidth for new device state. Jul 08 10:32:07 volumio kernel: usb 1-1.2: Not enough bandwidth for altsetting 6 Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19327, instance 1, run: true Jul 08 10:32:07 volumio volumio[976]: info: camilladsp spawned new process with pid 19334, instance 1, run: true Jul 08 10:32:07 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:07 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 46. Jul 08 10:32:07 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:07 volumio volumio[976]: info: Jul 08 10:32:07 volumio volumio[976]: ---------------------------- USB Audio Device Attached Jul 08 10:32:07 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 08 10:32:07 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Jul 08 10:32:08 volumio volumio[976]: info: Preparing to save Alsa Options, stopping services first Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::volumioPause Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::pause Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::stPlaybackTimer Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::servicePause Jul 08 10:32:08 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::servicePause Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 10:32:08 volumio volumio[976]: info: ControllerMpd::pause Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand pause Jul 08 10:32:08 volumio volumio[976]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"USB SB X-Fi Xtreme Audio","alsacard":"Audio"},"i2s":false} Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 10:32:08 volumio volumio[976]: info: Setting mixer PCM for card USB SB X-Fi Xtreme Audio Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 08 10:32:08 volumio volumio[976]: info: Updating Volume Controller Parameters: Device: 5 Name: USB SB X-Fi Xtreme Audio Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 08 10:32:08 volumio volumio[976]: info: Disabling external Volume Control Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 10:32:08 volumio volumio[976]: info: Preparing to generate the ALSA configuration file Jul 08 10:32:08 volumio volumio[976]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 08 10:32:08 volumio volumio[976]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 08 10:32:08 volumio volumio[976]: info: Reading ALSA contributions from plugins. Jul 08 10:32:08 volumio volumio[976]: info: Jul 08 10:32:08 volumio volumio[976]: ---------------------------- MPD announces state update: player Jul 08 10:32:08 volumio volumio[976]: info: sendMpdCommand pause took 262 milliseconds Jul 08 10:32:08 volumio volumio[976]: info: ControllerMpd::getState Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jul 08 10:32:08 volumio volumio[976]: info: Jul 08 10:32:08 volumio volumio[976]: ---------------------------- MPD announces state update: player Jul 08 10:32:08 volumio volumio[976]: info: ControllerMpd::getState Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jul 08 10:32:08 volumio volumio[976]: info: sendMpdCommand status took 25 milliseconds Jul 08 10:32:08 volumio volumio[976]: info: sendMpdCommand status took 25 milliseconds Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::parseState Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::parseState Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 10:32:08 volumio volumio[976]: info: camilladsp spawned new process with pid 19363, instance 1, run: true Jul 08 10:32:08 volumio volumio[976]: info: No valid Plugin REST Endpoint Jul 08 10:32:08 volumio volumio[976]: info: sendMpdCommand playlistinfo took 31 milliseconds Jul 08 10:32:08 volumio volumio[976]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::parseTrackInfo Jul 08 10:32:08 volumio volumio[976]: verbose: ControllerMpd::parseTrackInfo Jul 08 10:32:08 volumio volumio[976]: info: ControllerMpd::pushState Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::servicePushState Jul 08 10:32:08 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:08 volumio volumio[976]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2489,"duration":291,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"We Are (Symphonic Version)","artist":"HAEVN","album":"Symphonic Tales (Songs for String Orchestra)","uri":"https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456305&ei=EcCLZq6WLrrA6dsPt-GDWA&ip=193.107.74.210&id=o-AFYC0p5dkS87svYyqlLQQzG_3tf75vvY5niV0-Sj3fAk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=eP&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c2d&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671LIAGb3E9F_t6ZPGtIgpYBNMEJThcJWOKHMBuw9jj4mO7GDDiXVG73jV6xn6NR_j1GS2A&spc=NO7bAYjXXQ9twKslkNs1GyyFC62S264jHlUDlOZCLRUVWpttBWjfqOTvc-N7-hE&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kZACa5jUXc9VVnP47nWnWQ4Q&rqh=1&gir=yes&clen=4660727&dur=291.201&lmt=1715029315780240&mt=1720434356&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=Sqqt08arv2wk4w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRgIhAMpeA_du0EiASyEF0H5wkMbQUzZr8ZuZuEhN6khWWFWgAiEAryt0uTF1rRHS0TLdvWIiaTibL25toB_QktVzwiUoyX8%3D&sig=AJfQdSswRAIgPjOBddz3lNc9uryt4riIizSSCRrLwpLE3wIJFAn73RkCIGCQxg93mNLhPn1sWBpjcP0G9c5xaslZkloKqWr7ByGr&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jul 08 10:32:08 volumio volumio[976]: verbose: CURRENT POSITION 4 Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::syncState stateService pause Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus pause Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::stPlaybackTimer Jul 08 10:32:08 volumio volumio[976]: info: ControllerMpd::pushState Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::servicePushState Jul 08 10:32:08 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:08 volumio volumio[976]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2489,"duration":291,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"We Are (Symphonic Version)","artist":"HAEVN","album":"Symphonic Tales (Songs for String Orchestra)","uri":"https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456305&ei=EcCLZq6WLrrA6dsPt-GDWA&ip=193.107.74.210&id=o-AFYC0p5dkS87svYyqlLQQzG_3tf75vvY5niV0-Sj3fAk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=eP&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c2d&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671LIAGb3E9F_t6ZPGtIgpYBNMEJThcJWOKHMBuw9jj4mO7GDDiXVG73jV6xn6NR_j1GS2A&spc=NO7bAYjXXQ9twKslkNs1GyyFC62S264jHlUDlOZCLRUVWpttBWjfqOTvc-N7-hE&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kZACa5jUXc9VVnP47nWnWQ4Q&rqh=1&gir=yes&clen=4660727&dur=291.201&lmt=1715029315780240&mt=1720434356&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=Sqqt08arv2wk4w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRgIhAMpeA_du0EiASyEF0H5wkMbQUzZr8ZuZuEhN6khWWFWgAiEAryt0uTF1rRHS0TLdvWIiaTibL25toB_QktVzwiUoyX8%3D&sig=AJfQdSswRAIgPjOBddz3lNc9uryt4riIizSSCRrLwpLE3wIJFAn73RkCIGCQxg93mNLhPn1sWBpjcP0G9c5xaslZkloKqWr7ByGr&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jul 08 10:32:08 volumio volumio[976]: verbose: CURRENT POSITION 4 Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::syncState stateService pause Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus pause Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::stPlaybackTimer Jul 08 10:32:08 volumio volumio[976]: info: ------------------------------ 117ms Jul 08 10:32:08 volumio volumio[976]: info: ------------------------------ 115ms Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 10:32:08 volumio volumio[976]: info: VolumeController:: Volume=0 Mute =false Jul 08 10:32:08 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:08 volumio volumio[976]: info: Asound.conf file unchanged, so no further update is needed Jul 08 10:32:08 volumio volumio[976]: info: Output device has changed, restarting MPD Jul 08 10:32:08 volumio sudo[19375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 10:32:08 volumio sudo[19375]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:08 volumio volumio[976]: info: Output device has changed, restarting Shairport Sync Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:32:08 volumio sudo[19375]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:08 volumio sudo[19378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 10:32:08 volumio sudo[19378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:08 volumio systemd[1]: Stopping Music Player Daemon... Jul 08 10:32:08 volumio volumio[976]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 10:32:08 volumio volumio[976]: info: MPD Permissions set Jul 08 10:32:08 volumio systemd[1]: mpd.service: Succeeded. Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio systemd[1]: Stopped Music Player Daemon. Jul 08 10:32:08 volumio volumio[19347]: Traceback (most recent call last): Jul 08 10:32:08 volumio volumio[19347]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Jul 08 10:32:08 volumio volumio[19347]: from camilladsp import CamillaConnection Jul 08 10:32:08 volumio volumio[19347]: ModuleNotFoundError: No module named 'camilladsp' Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:32:08 volumio volumio[976]: info: Starting Shairport Sync Jul 08 10:32:08 volumio systemd[1]: Starting Music Player Daemon... Jul 08 10:32:08 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jul 08 10:32:08 volumio sudo[19389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 10:32:08 volumio sudo[19389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 08 10:32:08 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 08 10:32:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 08 10:32:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 08 10:32:08 volumio sudo[19389]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:08 volumio volumio[976]: info: Shairport-Sync Started Jul 08 10:32:08 volumio sudo[19383]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 10:32:08 volumio sudo[19383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:08 volumio sudo[19383]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:09 volumio mpd[19395]: Jul 08 10:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 08 10:32:09 volumio systemd[1]: Started Music Player Daemon. Jul 08 10:32:09 volumio sudo[19378]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:09 volumio volumio[976]: error: updateQueue error: null Jul 08 10:32:10 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 47. Jul 08 10:32:10 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:11 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:11 volumio volumio[19398]: Traceback (most recent call last): Jul 08 10:32:11 volumio volumio[19398]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Jul 08 10:32:11 volumio volumio[19398]: from camilladsp import CamillaConnection Jul 08 10:32:11 volumio volumio[19398]: ModuleNotFoundError: No module named 'camilladsp' Jul 08 10:32:11 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:11 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:11 volumio sudo[19400]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jul 08 10:32:11 volumio sudo[19400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:11 volumio sudo[19400]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:11 volumio sudo[19402]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jul 08 10:32:11 volumio sudo[19402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:11 volumio sudo[19402]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:11 volumio sudo[19404]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jul 08 10:32:11 volumio sudo[19404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:11 volumio sudo[19404]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:12 volumio volumio[976]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 08 10:32:12 volumio volumio[976]: info: CoreStateMachine::getcurrentVolume Jul 08 10:32:12 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume Jul 08 10:32:12 volumio volumio[976]: info: VolumeController:: Volume=0 Mute =false Jul 08 10:32:12 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:12 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:12 volumio volumio[976]: info: CoreStateMachine::updateTrackBlock Jul 08 10:32:12 volumio volumio[976]: info: CorePlayQueue::getTrackBlock Jul 08 10:32:12 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume Jul 08 10:32:12 volumio volumio[976]: info: VolumeController:: Volume=0 Mute =false Jul 08 10:32:12 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:12 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:13 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 48. Jul 08 10:32:13 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:13 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:14 volumio volumio[19411]: Traceback (most recent call last): Jul 08 10:32:14 volumio volumio[19411]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Jul 08 10:32:14 volumio volumio[19411]: from camilladsp import CamillaConnection Jul 08 10:32:14 volumio volumio[19411]: ModuleNotFoundError: No module named 'camilladsp' Jul 08 10:32:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:16 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 49. Jul 08 10:32:16 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:16 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:17 volumio volumio[19422]: Traceback (most recent call last): Jul 08 10:32:17 volumio volumio[19422]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Jul 08 10:32:17 volumio volumio[19422]: from camilladsp import CamillaConnection Jul 08 10:32:17 volumio volumio[19422]: ModuleNotFoundError: No module named 'camilladsp' Jul 08 10:32:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:19 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 50. Jul 08 10:32:19 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:19 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:19 volumio volumio[19433]: Traceback (most recent call last): Jul 08 10:32:19 volumio volumio[19433]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Jul 08 10:32:19 volumio volumio[19433]: from camilladsp import CamillaConnection Jul 08 10:32:19 volumio volumio[19433]: ModuleNotFoundError: No module named 'camilladsp' Jul 08 10:32:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:21 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 51. Jul 08 10:32:21 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:22 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:22 volumio volumio[19437]: Traceback (most recent call last): Jul 08 10:32:22 volumio volumio[19437]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 08 10:32:22 volumio volumio[19437]: from camilladsp_plot.validate_config import CamillaValidator Jul 08 10:32:22 volumio volumio[19437]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 08 10:32:22 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:22 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:24 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:24 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 52. Jul 08 10:32:24 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:25 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:25 volumio volumio[976]: info: CoreCommandRouter::volumioPlay Jul 08 10:32:25 volumio volumio[976]: info: CoreStateMachine::play index undefined Jul 08 10:32:25 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 10:32:25 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:25 volumio volumio[976]: info: CoreStateMachine::startPlaybackTimer Jul 08 10:32:25 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:25 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 10:32:25 volumio volumio[976]: info: ControllerMpd::resume Jul 08 10:32:25 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand play Jul 08 10:32:25 volumio volumio[976]: info: sendMpdCommand play took 1 milliseconds Jul 08 10:32:25 volumio volumio[19439]: Traceback (most recent call last): Jul 08 10:32:25 volumio volumio[19439]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 08 10:32:25 volumio volumio[19439]: from camilladsp_plot.validate_config import CamillaValidator Jul 08 10:32:25 volumio volumio[19439]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 08 10:32:25 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:25 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:27 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:27 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 53. Jul 08 10:32:27 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:28 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:28 volumio volumio[19460]: Traceback (most recent call last): Jul 08 10:32:28 volumio volumio[19460]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 08 10:32:28 volumio volumio[19460]: from camilladsp_plot.validate_config import CamillaValidator Jul 08 10:32:28 volumio volumio[19460]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 08 10:32:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:29 volumio volumio[976]: info: CoreCommandRouter::volumioPlay Jul 08 10:32:29 volumio volumio[976]: info: CoreStateMachine::play index undefined Jul 08 10:32:29 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 10:32:29 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:29 volumio volumio[976]: info: CoreStateMachine::startPlaybackTimer Jul 08 10:32:29 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:29 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 10:32:29 volumio volumio[976]: info: ControllerMpd::resume Jul 08 10:32:29 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand play Jul 08 10:32:29 volumio volumio[976]: info: sendMpdCommand play took 0 milliseconds Jul 08 10:32:30 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 54. Jul 08 10:32:30 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:31 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:31 volumio volumio[19462]: Traceback (most recent call last): Jul 08 10:32:31 volumio volumio[19462]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 08 10:32:31 volumio volumio[19462]: from camilladsp_plot.validate_config import CamillaValidator Jul 08 10:32:31 volumio volumio[19462]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 08 10:32:31 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:31 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:33 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:33 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 55. Jul 08 10:32:33 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:34 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:34 volumio volumio[19519]: Traceback (most recent call last): Jul 08 10:32:34 volumio volumio[19519]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 08 10:32:34 volumio volumio[19519]: from camilladsp_plot.validate_config import CamillaValidator Jul 08 10:32:34 volumio volumio[19519]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 08 10:32:34 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 08 10:32:34 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 08 10:32:36 volumio volumio[976]: info: CoreCommandRouter::volumioPlay Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::play index 6 Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::stop Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::updateTrackBlock Jul 08 10:32:36 volumio volumio[976]: info: CorePlayQueue::getTrackBlock Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::stPlaybackTimer Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:36 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:36 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:32:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::serviceStop Jul 08 10:32:36 volumio volumio[976]: info: CorePlayQueue::getTrack 4 Jul 08 10:32:36 volumio volumio[976]: info: CoreCommandRouter::serviceStop Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 10:32:36 volumio volumio[976]: info: ControllerMpd::stop Jul 08 10:32:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 10:32:36 volumio volumio[976]: info: sendMpdCommand stop took 5 milliseconds Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::play index undefined Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 10:32:36 volumio volumio[976]: info: CorePlayQueue::getTrack 6 Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::startPlaybackTimer Jul 08 10:32:36 volumio volumio[976]: info: CorePlayQueue::getTrack 6 Jul 08 10:32:36 volumio volumio[976]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22More%20Than%20a%20Feeling%20(Official%20HD%20Video)%22%2C%22artist%22%3A%22Boston%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Ft4QK8RxCAwo%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3klnOh3f05WfDzHpDlL3Mr7M7qYvQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22t4QK8RxCAwo%22%2C%22playlistId%22%3A%22LM%22%2C%22playlistSetVideoId%22%3A%2230892D90EC0C5586%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22t4QK8RxCAwo%22%2C%22playlistId%22%3A%22LM%22%2C%22playlistSetVideoId%22%3A%2230892D90EC0C5586%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D Jul 08 10:32:36 volumio volumio[976]: info: CorePlayQueue::getTrack 6 Jul 08 10:32:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 10:32:36 volumio volumio[976]: info: sendMpdCommand stop took 1 milliseconds Jul 08 10:32:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 10:32:36 volumio volumio[976]: info: Jul 08 10:32:36 volumio volumio[976]: ---------------------------- MPD announces system playlist update Jul 08 10:32:36 volumio volumio[976]: info: Ignoring MPD Status Update Jul 08 10:32:36 volumio volumio[976]: info: sendMpdCommand clear took 1 milliseconds Jul 08 10:32:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456356&ei=RMCLZvDeFcWFxs0Pg6KLaA&ip=193.107.74.210&id=o-AAMiEWPoVaXIkX-fGwYfBHxeJ3x1MDX3TFM_mS44JAjJ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=n-&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c2d&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671K98nGQHgJsBdekmlWfgyTsKQ0Ho9YEMyL6ZUmeFdHg_dMaVlY3OhVZe2Ao37h-jdOhjg&spc=NO7bAeVIXwNk6sBcNsGWveF_fBcE4QnFbXidzwtxq3y6MAHeHrV6Fg7kfocRkHI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pQZQzVKjuuRUSLGMjgND_YIQ&rqh=1&gir=yes&clen=3656989&dur=216.541&lmt=1714547390801726&mt=1720434356&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mt-nSKkz1shgyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhANouEJOpmFdjWIb7giiY-SGBQm_Ivh9-u3vROpSNDuE_AiB_U6kWd4gdeZLaZQPT8zfHd-tTYfhDbnR3QkrQgc9TNw%3D%3D&sig=AJfQdSswRQIgEnXzsMgneJJXzYg9xokvO-4irJGGusfPgQk886UEauwCIQCY0eYBgJIrjjErIz8FGTUEbEqQEXp1s06Py9Y7xkYRZg%3D%3D&cver=1.20211213.00.00&t.YouTube" Jul 08 10:32:36 volumio volumio[976]: error: updateQueue error: null Jul 08 10:32:36 volumio volumio[976]: info: Jul 08 10:32:36 volumio volumio[976]: ---------------------------- MPD announces system playlist update Jul 08 10:32:36 volumio volumio[976]: info: Ignoring MPD Status Update Jul 08 10:32:36 volumio volumio[976]: info: ------------------------------ 2ms Jul 08 10:32:36 volumio volumio[976]: info: sendMpdCommand addid "https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456356&ei=RMCLZvDeFcWFxs0Pg6KLaA&ip=193.107.74.210&id=o-AAMiEWPoVaXIkX-fGwYfBHxeJ3x1MDX3TFM_mS44JAjJ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=n-&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c2d&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671K98nGQHgJsBdekmlWfgyTsKQ0Ho9YEMyL6ZUmeFdHg_dMaVlY3OhVZe2Ao37h-jdOhjg&spc=NO7bAeVIXwNk6sBcNsGWveF_fBcE4QnFbXidzwtxq3y6MAHeHrV6Fg7kfocRkHI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pQZQzVKjuuRUSLGMjgND_YIQ&rqh=1&gir=yes&clen=3656989&dur=216.541&lmt=1714547390801726&mt=1720434356&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mt-nSKkz1shgyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhANouEJOpmFdjWIb7giiY-SGBQm_Ivh9-u3vROpSNDuE_AiB_U6kWd4gdeZLaZQPT8zfHd-tTYfhDbnR3QkrQgc9TNw%3D%3D&sig=AJfQdSswRQIgEnXzsMgneJJXzYg9xokvO-4irJGGusfPgQk886UEauwCIQCY0eYBgJIrjjErIz8FGTUEbEqQEXp1s06Py9Y7xkYRZg%3D%3D&cver=1.20211213.00.00&t.YouTube" took 2 milliseconds Jul 08 10:32:36 volumio volumio[976]: verbose: MPD COMMAND [object Object] Jul 08 10:32:36 volumio volumio[976]: verbose: MPD COMMAND [object Object] Jul 08 10:32:36 volumio volumio[976]: info: Jul 08 10:32:36 volumio volumio[976]: ---------------------------- MPD announces system playlist update Jul 08 10:32:36 volumio volumio[976]: info: Ignoring MPD Status Update Jul 08 10:32:36 volumio volumio[976]: info: ------------------------------ 3ms Jul 08 10:32:36 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 10:32:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand play Jul 08 10:32:36 volumio volumio[976]: info: ------------------------------ 2ms Jul 08 10:32:36 volumio volumio[976]: info: sendMpdCommand play took 1 milliseconds Jul 08 10:32:36 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 08 10:32:36 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 56. Jul 08 10:32:36 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 08 10:32:37 volumio systemd[1]: Started FusionDsp Daemon. Jul 08 10:32:37 volumio volumio[976]: info: Jul 08 10:32:37 volumio volumio[976]: ---------------------------- MPD announces state update: player Jul 08 10:32:37 volumio volumio[976]: info: ControllerMpd::getState Jul 08 10:32:37 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jul 08 10:32:37 volumio volumio[976]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jul 08 10:32:37 volumio volumio[976]: info: FusionDsp - ---- read samplerate from file: 48000 Jul 08 10:32:37 volumio volumio[976]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 08 10:32:37 volumio volumio[976]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jul 08 10:32:37 volumio volumio[976]: info: FusionDsp - ---- read samplerate from file: 48000 Jul 08 10:32:37 volumio volumio[976]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 08 10:32:37 volumio volumio[976]: info: Jul 08 10:32:37 volumio volumio[976]: ---------------------------- MPD announces state update: player Jul 08 10:32:37 volumio volumio[976]: info: sendMpdCommand status took 40 milliseconds Jul 08 10:32:37 volumio volumio[976]: info: ControllerMpd::getState Jul 08 10:32:37 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jul 08 10:32:37 volumio volumio[976]: verbose: ControllerMpd::parseState Jul 08 10:32:37 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 10:32:37 volumio volumio[976]: info: sendMpdCommand status took 7 milliseconds Jul 08 10:32:37 volumio volumio[976]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 08 10:32:37 volumio volumio[976]: verbose: ControllerMpd::parseState Jul 08 10:32:37 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 10:32:37 volumio volumio[976]: verbose: ControllerMpd::parseTrackInfo Jul 08 10:32:37 volumio volumio[976]: info: ControllerMpd::pushState Jul 08 10:32:37 volumio volumio[976]: info: CoreCommandRouter::servicePushState Jul 08 10:32:37 volumio volumio[976]: info: CorePlayQueue::getTrack 6 Jul 08 10:32:37 volumio volumio[976]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"More Than a Feeling (Official HD Video)","artist":"Boston","album":null,"uri":"https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456356&ei=RMCLZvDeFcWFxs0Pg6KLaA&ip=193.107.74.210&id=o-AAMiEWPoVaXIkX-fGwYfBHxeJ3x1MDX3TFM_mS44JAjJ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=n-&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c2d&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671K98nGQHgJsBdekmlWfgyTsKQ0Ho9YEMyL6ZUmeFdHg_dMaVlY3OhVZe2Ao37h-jdOhjg&spc=NO7bAeVIXwNk6sBcNsGWveF_fBcE4QnFbXidzwtxq3y6MAHeHrV6Fg7kfocRkHI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pQZQzVKjuuRUSLGMjgND_YIQ&rqh=1&gir=yes&clen=3656989&dur=216.541&lmt=1714547390801726&mt=1720434356&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mt-nSKkz1shgyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhANouEJOpmFdjWIb7giiY-SGBQm_Ivh9-u3vROpSNDuE_AiB_U6kWd4gdeZLaZQPT8zfHd-tTYfhDbnR3QkrQgc9TNw%3D%3D&sig=AJfQdSswRQIgEnXzsMgneJJXzYg9xokvO-4irJGGusfPgQk886UEauwCIQCY0eYBgJIrjjErIz8FGTUEbEqQEXp1s06Py9Y7xkYRZg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jul 08 10:32:37 volumio volumio[976]: verbose: CURRENT POSITION 6 Jul 08 10:32:37 volumio volumio[976]: info: CoreStateMachine::syncState stateService play Jul 08 10:32:37 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Jul 08 10:32:37 volumio volumio[976]: info: ------------------------------ 51ms Jul 08 10:32:37 volumio volumio[976]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 08 10:32:37 volumio volumio[976]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 08 10:32:37 volumio volumio[976]: verbose: ControllerMpd::parseTrackInfo Jul 08 10:32:37 volumio volumio[976]: info: ControllerMpd::pushState Jul 08 10:32:37 volumio volumio[976]: info: CoreCommandRouter::servicePushState Jul 08 10:32:37 volumio volumio[976]: info: CorePlayQueue::getTrack 6 Jul 08 10:32:37 volumio volumio[976]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1481,"duration":217,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"More Than a Feeling (Official HD Video)","artist":"Boston","album":null,"uri":"https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456356&ei=RMCLZvDeFcWFxs0Pg6KLaA&ip=193.107.74.210&id=o-AAMiEWPoVaXIkX-fGwYfBHxeJ3x1MDX3TFM_mS44JAjJ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=n-&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c2d&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671K98nGQHgJsBdekmlWfgyTsKQ0Ho9YEMyL6ZUmeFdHg_dMaVlY3OhVZe2Ao37h-jdOhjg&spc=NO7bAeVIXwNk6sBcNsGWveF_fBcE4QnFbXidzwtxq3y6MAHeHrV6Fg7kfocRkHI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pQZQzVKjuuRUSLGMjgND_YIQ&rqh=1&gir=yes&clen=3656989&dur=216.541&lmt=1714547390801726&mt=1720434356&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mt-nSKkz1shgyw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhANouEJOpmFdjWIb7giiY-SGBQm_Ivh9-u3vROpSNDuE_AiB_U6kWd4gdeZLaZQPT8zfHd-tTYfhDbnR3QkrQgc9TNw%3D%3D&sig=AJfQdSswRQIgEnXzsMgneJJXzYg9xokvO-4irJGGusfPgQk886UEauwCIQCY0eYBgJIrjjErIz8FGTUEbEqQEXp1s06Py9Y7xkYRZg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jul 08 10:32:37 volumio volumio[976]: verbose: CURRENT POSITION 6 Jul 08 10:32:37 volumio volumio[976]: info: CoreStateMachine::syncState stateService play Jul 08 10:32:37 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus play Jul 08 10:32:37 volumio volumio[976]: info: Received an update from plugin. extracting info from payload Jul 08 10:32:37 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:32:37 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:37 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:37 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:37 volumio volumio[976]: info: ------------------------------ 30ms Jul 08 10:32:37 volumio volumio[976]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 08 10:32:38 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:38 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:38 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:38 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:39 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:39 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:39 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:39 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:40 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:40 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:40 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:40 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:40 volumio sudo[19540]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Jul 08 10:32:40 volumio sudo[19540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:40 volumio sudo[19540]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:40 volumio sudo[19542]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Jul 08 10:32:40 volumio sudo[19542]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:40 volumio sudo[19542]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:41 volumio sudo[19544]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jul 08 10:32:41 volumio sudo[19544]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 10:32:41 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:41 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:41 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:41 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:41 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:42 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:42 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:42 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:42 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:43 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:43 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:43 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:43 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:44 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:44 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:44 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:44 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:45 volumio sudo[19544]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:45 volumio sudo[1382]: pam_unix(sudo:session): session closed for user root Jul 08 10:32:45 volumio volumio[976]: info: Install script completed Jul 08 10:32:45 volumio volumio[976]: info: Adding reference to registry Jul 08 10:32:45 volumio volumio[976]: info: Done installing plugin. Jul 08 10:32:45 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 10:32:45 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 08 10:32:45 volumio volumio[976]: info: Folder /data/temp removed Jul 08 10:32:45 volumio volumio[976]: info: Folder /tmp/plugins removed Jul 08 10:32:45 volumio volumio[976]: info: Folder /tmp/downloaded_plugin.zip removed Jul 08 10:32:45 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:45 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:45 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:45 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:46 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:46 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:46 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:46 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:47 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:47 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:47 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:47 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:48 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:48 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:48 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:48 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:49 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:49 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:49 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:49 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:50 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:50 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:50 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:50 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:51 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:51 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:51 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:51 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:52 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:52 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:52 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:52 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:53 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:53 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:53 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:53 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:54 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:54 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:54 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:54 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:55 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:55 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:55 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:55 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:56 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:56 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:56 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:56 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:57 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:57 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:57 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:57 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:58 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:32:58 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:32:58 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:32:58 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:32:59 volumio volumio[976]: info: CoreCommandRouter::volumioNext Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::next Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 10:32:59 volumio volumio[976]: info: ControllerMpd::next Jul 08 10:32:59 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand next Jul 08 10:32:59 volumio volumio[976]: info: Jul 08 10:32:59 volumio volumio[976]: ---------------------------- MPD announces state update: player Jul 08 10:32:59 volumio volumio[976]: info: sendMpdCommand next took 8 milliseconds Jul 08 10:32:59 volumio volumio[976]: info: ControllerMpd::getState Jul 08 10:32:59 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jul 08 10:32:59 volumio volumio[976]: info: sendMpdCommand status took 2 milliseconds Jul 08 10:32:59 volumio volumio[976]: verbose: ControllerMpd::parseState Jul 08 10:32:59 volumio volumio[976]: info: ControllerMpd::pushState Jul 08 10:32:59 volumio volumio[976]: info: CoreCommandRouter::servicePushState Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:59 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:32:59 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:59 volumio volumio[976]: info: CorePlayQueue::getTrack 6 Jul 08 10:32:59 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 10:32:59 volumio volumio[976]: verbose: CURRENT POSITION 6 Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus play Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::play index undefined Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:59 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:32:59 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:32:59 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:59 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::startPlaybackTimer Jul 08 10:32:59 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:32:59 volumio volumio[976]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Layers%20of%20Life%22%2C%22artist%22%3A%22Emil%20Brandqvist%20Trio%22%2C%22album%22%3A%22Layers%20of%20Life%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F8xkkzAEChZdz5GF8Zkx-V0xzQGEwfC2-C49u1o-3CajjjCoUjfIfWFRNAr6zJX3ebvSNBsAt2Jo0OgM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22BEBevO4vrUU%22%2C%22playlistId%22%3A%22LM%22%2C%22playlistSetVideoId%22%3A%225396A0119349808E%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22BEBevO4vrUU%22%2C%22playlistId%22%3A%22LM%22%2C%22playlistSetVideoId%22%3A%225396A0119349808E%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Jul 08 10:32:59 volumio volumio[976]: info: ------------------------------ 15ms Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:32:59 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:32:59 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:32:59 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:32:59 volumio volumio[976]: info: camilladsp spawned new process with pid 19579, instance 1, run: true Jul 08 10:32:59 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:32:59 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 10:32:59 volumio volumio[976]: info: sendMpdCommand stop took 4 milliseconds Jul 08 10:32:59 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 10:32:59 volumio volumio[976]: info: Jul 08 10:32:59 volumio volumio[976]: ---------------------------- MPD announces system playlist update Jul 08 10:32:59 volumio volumio[976]: info: Ignoring MPD Status Update Jul 08 10:32:59 volumio volumio[976]: info: sendMpdCommand clear took 9 milliseconds Jul 08 10:32:59 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456379&ei=W8CLZpq4F_qPxN8Puq2fwAM&ip=193.107.74.210&id=o-ADQLB4zasplA4e8_G1IM76y1pmdyDDR7ccL2-cIyPj28&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=99&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c26&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671JSeFWiTK2AEvXH_cAkKf-sJumvdi_atZP_ERLJsxyw_p3_65O9Pke0LtN-db0KJmj0oA&spc=NO7bARxbbpvOYZZRG2oLWZuIbdLFB3rgbLJFFl73QY-bC_kKSCoeGjWLSTUNG5o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=UuKf7Si6wT9KZVt1_yU__OgQ&rqh=1&gir=yes&clen=7203547&dur=380.221&lmt=1714531260413166&mt=1720434356&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=nbOPca4jfDwDvg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgVqz7Jis0GZnDGQkgnM2LEdNU2Z6wYWGtMbS5BOAnROsCIBIAGwcu-7bQGvmxgWax74mb94_weyBppFQ7nD1x1Y0e&sig=AJfQdSswRQIhAMz3MEyPmzINo0dIXm8Mr-YDNZc1pJziK-OCIfg9YDfjAiBsqHwrceekPTF4FFAnk__ItQ9__FsQltj44g1ecUCNHA%3D%3D&cver=1.20211213.00.00&t.YouTube" Jul 08 10:32:59 volumio volumio[976]: error: updateQueue error: null Jul 08 10:32:59 volumio volumio[976]: info: Jul 08 10:32:59 volumio volumio[976]: ---------------------------- MPD announces system playlist update Jul 08 10:32:59 volumio volumio[976]: info: Ignoring MPD Status Update Jul 08 10:32:59 volumio volumio[976]: info: ------------------------------ 2ms Jul 08 10:32:59 volumio volumio[976]: info: sendMpdCommand addid "https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456379&ei=W8CLZpq4F_qPxN8Puq2fwAM&ip=193.107.74.210&id=o-ADQLB4zasplA4e8_G1IM76y1pmdyDDR7ccL2-cIyPj28&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=99&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c26&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671JSeFWiTK2AEvXH_cAkKf-sJumvdi_atZP_ERLJsxyw_p3_65O9Pke0LtN-db0KJmj0oA&spc=NO7bARxbbpvOYZZRG2oLWZuIbdLFB3rgbLJFFl73QY-bC_kKSCoeGjWLSTUNG5o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=UuKf7Si6wT9KZVt1_yU__OgQ&rqh=1&gir=yes&clen=7203547&dur=380.221&lmt=1714531260413166&mt=1720434356&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=nbOPca4jfDwDvg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgVqz7Jis0GZnDGQkgnM2LEdNU2Z6wYWGtMbS5BOAnROsCIBIAGwcu-7bQGvmxgWax74mb94_weyBppFQ7nD1x1Y0e&sig=AJfQdSswRQIhAMz3MEyPmzINo0dIXm8Mr-YDNZc1pJziK-OCIfg9YDfjAiBsqHwrceekPTF4FFAnk__ItQ9__FsQltj44g1ecUCNHA%3D%3D&cver=1.20211213.00.00&t.YouTube" took 2 milliseconds Jul 08 10:32:59 volumio volumio[976]: verbose: MPD COMMAND [object Object] Jul 08 10:32:59 volumio volumio[976]: info: Jul 08 10:32:59 volumio volumio[976]: ---------------------------- MPD announces system playlist update Jul 08 10:32:59 volumio volumio[976]: info: Ignoring MPD Status Update Jul 08 10:32:59 volumio volumio[976]: info: ------------------------------ 4ms Jul 08 10:32:59 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 10:32:59 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand play Jul 08 10:32:59 volumio volumio[976]: info: ------------------------------ 3ms Jul 08 10:32:59 volumio volumio[976]: info: sendMpdCommand play took 2 milliseconds Jul 08 10:33:00 volumio volumio[976]: info: Jul 08 10:33:00 volumio volumio[976]: ---------------------------- MPD announces state update: player Jul 08 10:33:00 volumio volumio[976]: info: ControllerMpd::getState Jul 08 10:33:00 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jul 08 10:33:00 volumio volumio[976]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jul 08 10:33:00 volumio volumio[976]: info: FusionDsp - ---- read samplerate from file: 48000 Jul 08 10:33:00 volumio volumio[976]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 08 10:33:00 volumio volumio[976]: info: Jul 08 10:33:00 volumio volumio[976]: ---------------------------- MPD announces state update: player Jul 08 10:33:00 volumio volumio[976]: info: sendMpdCommand status took 17 milliseconds Jul 08 10:33:00 volumio volumio[976]: info: ControllerMpd::getState Jul 08 10:33:00 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jul 08 10:33:00 volumio volumio[976]: verbose: ControllerMpd::parseState Jul 08 10:33:00 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 10:33:00 volumio volumio[976]: info: sendMpdCommand status took 6 milliseconds Jul 08 10:33:00 volumio volumio[976]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 08 10:33:00 volumio volumio[976]: verbose: ControllerMpd::parseState Jul 08 10:33:00 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 10:33:00 volumio volumio[976]: verbose: ControllerMpd::parseTrackInfo Jul 08 10:33:00 volumio volumio[976]: info: ControllerMpd::pushState Jul 08 10:33:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState Jul 08 10:33:00 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:33:00 volumio volumio[976]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":380,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Layers of Life","artist":"Emil Brandqvist Trio","album":"Layers of Life","uri":"https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456379&ei=W8CLZpq4F_qPxN8Puq2fwAM&ip=193.107.74.210&id=o-ADQLB4zasplA4e8_G1IM76y1pmdyDDR7ccL2-cIyPj28&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=99&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c26&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671JSeFWiTK2AEvXH_cAkKf-sJumvdi_atZP_ERLJsxyw_p3_65O9Pke0LtN-db0KJmj0oA&spc=NO7bARxbbpvOYZZRG2oLWZuIbdLFB3rgbLJFFl73QY-bC_kKSCoeGjWLSTUNG5o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=UuKf7Si6wT9KZVt1_yU__OgQ&rqh=1&gir=yes&clen=7203547&dur=380.221&lmt=1714531260413166&mt=1720434356&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=nbOPca4jfDwDvg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgVqz7Jis0GZnDGQkgnM2LEdNU2Z6wYWGtMbS5BOAnROsCIBIAGwcu-7bQGvmxgWax74mb94_weyBppFQ7nD1x1Y0e&sig=AJfQdSswRQIhAMz3MEyPmzINo0dIXm8Mr-YDNZc1pJziK-OCIfg9YDfjAiBsqHwrceekPTF4FFAnk__ItQ9__FsQltj44g1ecUCNHA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jul 08 10:33:00 volumio volumio[976]: verbose: CURRENT POSITION 7 Jul 08 10:33:00 volumio volumio[976]: info: CoreStateMachine::syncState stateService play Jul 08 10:33:00 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Jul 08 10:33:00 volumio volumio[976]: info: ------------------------------ 26ms Jul 08 10:33:00 volumio volumio[976]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 08 10:33:00 volumio volumio[976]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 08 10:33:00 volumio volumio[976]: verbose: ControllerMpd::parseTrackInfo Jul 08 10:33:00 volumio volumio[976]: info: ControllerMpd::pushState Jul 08 10:33:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState Jul 08 10:33:00 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:33:00 volumio volumio[976]: verbose: STATE SERVICE {"status":"play","position":0,"seek":326,"duration":380,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Layers of Life","artist":"Emil Brandqvist Trio","album":"Layers of Life","uri":"https://rr2---sn-oxujput03g-1uge.googlevideo.com/videoplayback?expire=1720456379&ei=W8CLZpq4F_qPxN8Puq2fwAM&ip=193.107.74.210&id=o-ADQLB4zasplA4e8_G1IM76y1pmdyDDR7ccL2-cIyPj28&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=99&mm=31%2C29&mn=sn-oxujput03g-1uge%2Csn-8vap5-3c26&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=ua&initcwndbps=1266250&siu=1&bui=AXc671JSeFWiTK2AEvXH_cAkKf-sJumvdi_atZP_ERLJsxyw_p3_65O9Pke0LtN-db0KJmj0oA&spc=NO7bARxbbpvOYZZRG2oLWZuIbdLFB3rgbLJFFl73QY-bC_kKSCoeGjWLSTUNG5o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=UuKf7Si6wT9KZVt1_yU__OgQ&rqh=1&gir=yes&clen=7203547&dur=380.221&lmt=1714531260413166&mt=1720434356&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=nbOPca4jfDwDvg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgVqz7Jis0GZnDGQkgnM2LEdNU2Z6wYWGtMbS5BOAnROsCIBIAGwcu-7bQGvmxgWax74mb94_weyBppFQ7nD1x1Y0e&sig=AJfQdSswRQIhAMz3MEyPmzINo0dIXm8Mr-YDNZc1pJziK-OCIfg9YDfjAiBsqHwrceekPTF4FFAnk__ItQ9__FsQltj44g1ecUCNHA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jul 08 10:33:00 volumio volumio[976]: verbose: CURRENT POSITION 7 Jul 08 10:33:00 volumio volumio[976]: info: CoreStateMachine::syncState stateService play Jul 08 10:33:00 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus play Jul 08 10:33:00 volumio volumio[976]: info: Received an update from plugin. extracting info from payload Jul 08 10:33:00 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:33:00 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:33:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:33:00 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:33:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:33:00 volumio volumio[976]: info: ------------------------------ 20ms Jul 08 10:33:00 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:00 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:00 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:00 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:01 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:01 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:01 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:01 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:02 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:02 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:02 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:02 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:03 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:03 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:03 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:03 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:04 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:04 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:04 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:04 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:05 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:05 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:05 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:05 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:06 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:06 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:06 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:06 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:07 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:07 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:07 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:07 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:08 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:08 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:08 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:08 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:09 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:09 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:09 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:09 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:10 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:10 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:10 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:10 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:11 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:11 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:11 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:11 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:11 volumio volumio[976]: info: VolumeController::SetAlsaVolume77 Jul 08 10:33:11 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:33:11 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:33:11 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:33:12 volumio volumio[976]: info: VolumeController::SetAlsaVolume81 Jul 08 10:33:12 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:33:12 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:33:12 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:33:12 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:12 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:12 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:12 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:13 volumio volumio[976]: info: VolumeController::SetAlsaVolume100 Jul 08 10:33:13 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:33:13 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:33:13 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:33:13 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:13 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:13 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:13 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:14 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:14 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:14 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:14 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:15 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:15 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:15 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:15 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:16 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:16 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:16 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:16 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:17 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:17 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:17 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:17 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:18 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:18 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:18 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:18 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:19 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:19 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:19 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:19 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:20 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:20 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:20 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:20 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:21 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:21 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:21 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:21 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:22 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:22 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:22 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:22 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:23 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:23 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:23 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:23 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:24 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:24 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:24 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:24 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:25 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:25 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:25 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:25 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:26 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:26 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:26 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:26 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 10:33:27 volumio volumio[976]: info: CURURI: music-library Jul 08 10:33:27 volumio volumio[976]: info: Preload queue cleared Jul 08 10:33:27 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:27 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:27 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:27 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:28 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:28 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:28 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:28 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:29 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 10:33:29 volumio volumio[976]: info: CURURI: music-library/INTERNAL Jul 08 10:33:29 volumio volumio[976]: info: Preload queue cleared Jul 08 10:33:29 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:29 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:29 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:29 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:30 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:30 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:30 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:30 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:31 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:31 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:31 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:31 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:32 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:32 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:32 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:32 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:33 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:33 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:33 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:33 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:34 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 10:33:34 volumio volumio[976]: info: CURURI: music-library/INTERNAL/Mike Oldfield (Discografia) Jul 08 10:33:34 volumio volumio[976]: info: Preload queue cleared Jul 08 10:33:34 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:34 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:34 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:34 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:35 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:35 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:35 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:35 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:36 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:36 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:36 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:36 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:37 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:37 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:37 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:37 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:38 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:38 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:38 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:38 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:39 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:39 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:39 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:39 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:40 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:40 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:40 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:40 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:41 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:41 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:41 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:41 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:42 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:42 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:42 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:42 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:43 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:43 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:43 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:43 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:44 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:44 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:44 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:44 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:45 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:45 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:45 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:45 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:46 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:46 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:46 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:46 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:47 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:47 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:47 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:47 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:48 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:48 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:48 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:48 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:49 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:49 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:49 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:49 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:50 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:50 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:50 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:50 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:51 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:51 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:51 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:51 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:52 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:52 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:52 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:52 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:53 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:53 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:53 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:53 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:54 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:54 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:54 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:54 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:56 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:56 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:56 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:56 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:57 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Jul 08 10:33:57 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::getQueue Jul 08 10:33:57 volumio volumio[976]: info: CorePlayQueue::getQueue Jul 08 10:33:57 volumio volumio[976]: info: Preload queue cleared Jul 08 10:33:57 volumio volumio[976]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::ClearQueue Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::stop Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::stPlaybackTimer Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::updateTrackBlock Jul 08 10:33:57 volumio volumio[976]: info: CorePlayQueue::getTrackBlock Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::pushState Jul 08 10:33:57 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:33:57 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:33:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::serviceStop Jul 08 10:33:57 volumio volumio[976]: info: CorePlayQueue::getTrack 7 Jul 08 10:33:57 volumio volumio[976]: info: CoreCommandRouter::serviceStop Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 10:33:57 volumio volumio[976]: info: ControllerMpd::stop Jul 08 10:33:57 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 10:33:57 volumio volumio[976]: info: CorePlayQueue::clearPlayQueue Jul 08 10:33:57 volumio volumio[976]: info: CorePlayQueue::saveQueue Jul 08 10:33:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushQueue Jul 08 10:33:57 volumio volumio[976]: info: CoreStateMachine::addQueueItems Jul 08 10:33:57 volumio volumio[976]: info: CorePlayQueue::addQueueItems Jul 08 10:33:57 volumio volumio[976]: info: Preload queue cleared Jul 08 10:33:57 volumio volumio[976]: info: Adding Item to queue: music-library/INTERNAL/Mike Oldfield (Discografia)/1992 - Tubular Bells II Jul 08 10:33:57 volumio volumio[976]: info: Exploding uri music-library/INTERNAL/Mike Oldfield (Discografia)/1992 - Tubular Bells II in service mpd Jul 08 10:33:57 volumio volumio[976]: info: Jul 08 10:33:57 volumio volumio[976]: ---------------------------- MPD announces state update: player Jul 08 10:33:57 volumio volumio[976]: info: ALBUMART /albumart?cacheid=243&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMike%20Oldfield%20(Discografia)%2F1992%20-%20Tubular%20Bells%20II%2FMike%20Oldfield%20-%20Tubular%20Bells%20II.ape&metadata=false Jul 08 10:33:57 volumio volumio[976]: info: URI /mnt/INTERNAL/Mike Oldfield (Discografia)/1992 - Tubular Bells II/Mike Oldfield - Tubular Bells II.ape Jul 08 10:33:57 volumio volumio[976]: info: ALBUMART /albumart?cacheid=243&web=Mike%20Oldfield/Tubular%20Bells%20II/extralarge&path=%2Fmnt%2FINTERNAL%2FMike%20Oldfield%20(Discografia)%2F1992%20-%20Tubular%20Bells%20II%2FMike%20Oldfield%20-%20Tubular%20Bells%20II.ape.cue&metadata=false Jul 08 10:33:57 volumio volumio[976]: info: URI /mnt/INTERNAL/Mike Oldfield (Discografia)/1992 - Tubular Bells II/Mike Oldfield - Tubular Bells II.ape.cue Jul 08 10:33:57 volumio volumio[976]: info: ALBUMART /albumart?cacheid=243&web=Mike%20Oldfield/Tubular%20Bells%20II/extralarge&path=%2Fmnt%2FINTERNAL%2FMike%20Oldfield%20(Discografia)%2F1992%20-%20Tubular%20Bells%20II%2FMike%20Oldfield%20-%20Tubular%20Bells%20II.ape.cue&metadata=false Jul 08 10:33:57 volumio volumio[976]: info: URI /mnt/INTERNAL/Mike Oldfield (Discografia)/1992 - Tubular Bells II/Mike Oldfield - Tubular Bells II.ape.cue Jul 08 10:33:57 volumio volumio[976]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 10:33:57 volumio volumio[976]: Error: Unable to resolve or reject the same promise twice Jul 08 10:33:57 volumio volumio[976]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 08 10:33:57 volumio volumio[976]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Jul 08 10:33:57 volumio volumio[976]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 08 10:33:57 volumio volumio[976]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 08 10:33:57 volumio volumio[976]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 08 10:33:57 volumio volumio[976]: at Socket.emit (events.js:315:20) Jul 08 10:33:57 volumio volumio[976]: at addChunk (internal/streams/readable.js:309:12) Jul 08 10:33:57 volumio volumio[976]: at readableAddChunk (internal/streams/readable.js:280:11) Jul 08 10:33:57 volumio volumio[976]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jul 08 10:33:57 volumio volumio[976]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 08 10:33:57 volumio volumio[976]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 10:33:57 volumio sudo[19658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-08 10:32 Jul 08 10:33:57 volumio sudo[19658]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"