-- Logs begin at Sun 2025-06-08 08:53:46 CEST, end at Sun 2025-06-08 09:37:37 CEST. -- Jun 08 09:36:00 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:00 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:00 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:00 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:00 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:00 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:00 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:00 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:00 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:01 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:01 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:01 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:01 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:01 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:01 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:01 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:01 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:01 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:02 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:02 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:02 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:02 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:02 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:02 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:02 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:02 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:02 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:02 volumio qobuz-connect[1743]: 20250608 09:36:02.527 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:36:02 volumio qobuz-connect[1743]: 20250608 09:36:02.527 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:36:02 volumio qobuz-connect[1743]: 20250608 09:36:02.527 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:36:02 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:02 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:03 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:03 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:03 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:03 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:03 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:03 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:03 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:03 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:03 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:04 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:04 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:04 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:04 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:04 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:04 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:04 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:04 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:04 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:05 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:05 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:05 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:05 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:05 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:05 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:05 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:05 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:05 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:06 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:06 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:06 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:06 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:06 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:06 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:06 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:06 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:06 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:07 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:07 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:07 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:07 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:07 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:07 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:07 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:07 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:07 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:08 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:08 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:08 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:08 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:08 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:08 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:08 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:08 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:08 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:09 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:09 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:09 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:09 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:09 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:09 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:09 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:09 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:09 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:10 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:10 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:10 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:10 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:10 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:10 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:10 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:10 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:10 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:11 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:11 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:11 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:11 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:11 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:11 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:11 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:11 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:11 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:12 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:12 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:12 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:12 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:12 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:12 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:12 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:12 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:12 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:12 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:12 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:12 volumio qobuz-connect[1743]: 20250608 09:36:12.528 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:36:12 volumio qobuz-connect[1743]: 20250608 09:36:12.528 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:36:12 volumio qobuz-connect[1743]: 20250608 09:36:12.528 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:36:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:13 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:13 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:13 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:13 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:13 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:13 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:13 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:14 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:14 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:14 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:14 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:14 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:14 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:14 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:14 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:14 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:15 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:15 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:15 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:15 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:15 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:15 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:15 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:15 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:15 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:16 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:16 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:16 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:16 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:16 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:16 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:16 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:16 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:16 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:17 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:17 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:17 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:17 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:17 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:17 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:17 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:17 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:17 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:18 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:18 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:18 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:18 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:18 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:18 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:18 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:18 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:18 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:19 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:19 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:19 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:19 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:19 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:19 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:19 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:19 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:19 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:20 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:20 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:20 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:20 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:20 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:20 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:20 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:20 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:20 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:20 volumio vtcs[1810]: [2025-06-08 09:36:20.513] [tisoc] [warning] [SessionManagerImpl.cpp:147] No Session: IDLE Jun 08 09:36:20 volumio vtcs[1810]: [2025-06-08 09:36:20] [info] asio async_shutdown error: asio.misc:2 (End of file) Jun 08 09:36:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:21 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:21 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:21 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:21 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:21 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:21 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:21 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:22 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:22 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:22 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:22 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:22 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:22 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:22 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:22 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:22 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:22 volumio qobuz-connect[1743]: 20250608 09:36:22.529 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:36:22 volumio qobuz-connect[1743]: 20250608 09:36:22.529 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:36:22 volumio qobuz-connect[1743]: 20250608 09:36:22.529 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:36:22 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:22 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:23 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:23 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:23 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:23 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:23 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:23 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:24 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:24 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:24 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:24 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:24 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:24 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:24 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:24 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:24 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:25 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:25 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:25 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:25 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:25 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:25 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:25 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:25 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:25 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:26 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:26 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:26 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:26 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:26 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:26 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:26 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:26 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:26 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:27 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:27 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:27 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:27 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:27 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:27 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:27 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:27 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:27 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:28 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:28 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:28 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:28 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:28 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:28 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:28 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:28 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:28 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:29 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:29 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:29 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:29 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:29 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:29 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:29 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:29 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:29 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:30 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:30 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:30 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:30 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:30 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:30 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:30 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:31 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:31 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:31 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:31 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:31 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:31 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:31 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:31 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:31 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:32 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:32 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:32 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:32 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:32 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:32 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:32 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:32 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:32 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:32 volumio qobuz-connect[1743]: 20250608 09:36:32.530 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:36:32 volumio qobuz-connect[1743]: 20250608 09:36:32.530 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:36:32 volumio qobuz-connect[1743]: 20250608 09:36:32.530 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:36:32 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:32 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:33 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:33 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:33 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:33 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:33 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:33 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:33 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:34 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:34 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:34 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:34 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:34 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:34 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:34 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:34 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:34 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:35 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:35 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:35 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:35 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:35 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:35 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:35 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:36 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:36 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:36 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:36 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:36 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:36 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:36 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:37 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:37 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:37 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:37 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:37 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:37 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:37 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:37 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:37 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:38 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:38 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:38 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:38 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:38 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:38 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:38 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:38 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:38 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:39 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:39 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:39 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:39 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:39 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:39 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:39 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:39 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:39 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:40 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:40 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:40 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:40 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:40 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:40 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:40 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:40 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:40 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:41 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:41 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:41 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:41 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:41 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:41 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:41 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:41 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:41 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:42 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:42 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:42 volumio volumio[842]: info: Currently active: webradio Jun 08 09:36:42 volumio volumio[842]: info: Stopping currently active service Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioStop Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::stop Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Jun 08 09:36:42 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:42 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:42 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::serviceStop Jun 08 09:36:42 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::serviceStop Jun 08 09:36:42 volumio volumio[842]: info: [1749368202282] ControllerWebradio::stop Jun 08 09:36:42 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 09:36:42 volumio volumio[842]: info: sendMpdCommand stop took 7 milliseconds Jun 08 09:36:42 volumio volumio[842]: info: TidalConnect Active Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:42 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:36:42 volumio volumio[842]: info: Currently active: webradio Jun 08 09:36:42 volumio volumio[842]: info: Setting Volatile state to tidalconnect Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:42 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:42 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:42 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:42 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:42 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:42 volumio qobuz-connect[1743]: 20250608 09:36:42.532 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:36:42 volumio qobuz-connect[1743]: 20250608 09:36:42.532 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:36:42 volumio qobuz-connect[1743]: 20250608 09:36:42.532 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:36:42 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:43 volumio vtcs[1810]: [2025-06-08 09:36:43.123] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 Jun 08 09:36:43 volumio vtcs[1810]: [2025-06-08 09:36:43.123] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Jun 08 09:36:43 volumio volumio[842]: info: Pushing metadata Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:43 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:43 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:43 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:43 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:43 volumio volumio[842]: info: [LastFM] Current track has sufficient metadata: title (Mulata) and artist (Raul Paz) passed on explicitly Jun 08 09:36:43 volumio volumio[842]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:43 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:43 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:43 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:43 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:43 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:43 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:43 volumio volumio[842]: info: Pushing metadata Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:43 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:43 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:43 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:43 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:44 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:44 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:44 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:44 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:44 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:44 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:44 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:44 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:45 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:45 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:45 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:45 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:45 volumio vtcs[1810]: [2025-06-08 09:36:45.378] [tisoc] [error] [pcm_sink_alsa.cpp:114] Failed to open device volumio, error: Device or resource busy Jun 08 09:36:45 volumio vtcs[1810]: [2025-06-08 09:36:45.378] [tisoc] [error] [media_player_tidal.cpp:178] Could not open pcm sink: 1 Jun 08 09:36:45 volumio volumio[842]: info: Unknown command: notifyPlaybackError Jun 08 09:36:45 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:45 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:45 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:45 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:46 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:46 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:46 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:46 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:46 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:46 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:46 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:46 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:47 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:47 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:47 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:47 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:47 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:47 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:47 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:47 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:48 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:48 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:48 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:48 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:48 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:48 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:48 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:48 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:49 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:49 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:49 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:49 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:49 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:49 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:49 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:49 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:50 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:50 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:50 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:50 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:50 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:50 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:50 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:50 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:51 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:51 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:51 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:51 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:51 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:51 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:51 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:51 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:52 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:52 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:52 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:52 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:52 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:52 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:52 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:52 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:52 volumio qobuz-connect[1743]: 20250608 09:36:52.532 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:36:52 volumio qobuz-connect[1743]: 20250608 09:36:52.532 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:36:52 volumio qobuz-connect[1743]: 20250608 09:36:52.532 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:36:52 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:53 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:53 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:53 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:53 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:53 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:53 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:53 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:53 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:54 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:54 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:54 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:54 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:54 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:54 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:54 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:54 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:54 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:54 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:54 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:36:54 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:54 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:55 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:55 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:55 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:55 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:55 volumio vtcs[1810]: [2025-06-08 09:36:55.853] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=5, playstate_=1, player_state_=0 Jun 08 09:36:55 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:36:55 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:36:55 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:36:55 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:36:55 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:56 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:56 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:56 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:56 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:56 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:56 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:56 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:56 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:57 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:57 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:57 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:57 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:57 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:57 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:57 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:57 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:58 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:58 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:58 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:58 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:58 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:58 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:58 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:58 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:59 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:36:59 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:36:59 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:36:59 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:36:59 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:59 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:36:59 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:36:59 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:00 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:00 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:00 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:00 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:00 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:00 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:00 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:00 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:01 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:01 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:01 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:01 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:01 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:01 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:01 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:01 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:02 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:02 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:02 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:02 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:02 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:02 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:02 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:02 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:02 volumio qobuz-connect[1743]: 20250608 09:37:02.533 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:02 volumio qobuz-connect[1743]: 20250608 09:37:02.533 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:02 volumio qobuz-connect[1743]: 20250608 09:37:02.533 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:02 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:03 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:03 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:03 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:03 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:03 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:03 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:03 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:03 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:04 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:04 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:04 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:04 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:04 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:04 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:04 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:04 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:05 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:05 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:05 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:05 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:05 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:05 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:05 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:05 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:06 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:06 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:06 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:06 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:06 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:06 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:06 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:06 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:07 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:07 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:07 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:07 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:07 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:07 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:07 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:07 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:08 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:08 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:08 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:08 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:08 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:08 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:08 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:08 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:09 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:09 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:09 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:09 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:09 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:09 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:09 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:09 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:10 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:10 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:10 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:10 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:10 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:10 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:10 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:10 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:11 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:11 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:11 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:11 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:11 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:11 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:11 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:11 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:12 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:12 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:12 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:12 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:12 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:12 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:12 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:12 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:12 volumio qobuz-connect[1743]: 20250608 09:37:12.533 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:12 volumio qobuz-connect[1743]: 20250608 09:37:12.533 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:12 volumio qobuz-connect[1743]: 20250608 09:37:12.533 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:12 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:12 volumio volumio[842]: info: PlaybackInactive Jun 08 09:37:12 volumio volumio[842]: info: Device Session is_active: true Jun 08 09:37:12 volumio volumio[842]: info: DeviceInactive Jun 08 09:37:12 volumio volumio[842]: info: Relinquishing Volumio State Jun 08 09:37:12 volumio volumio[842]: verbose: UNSET VOLATILE: Service: tidalconnect Jun 08 09:37:12 volumio volumio[842]: info: unSetVolatile called Jun 08 09:37:12 volumio volumio[842]: info: Relinquishing Volumio State to another service Jun 08 09:37:12 volumio vtcs[1810]: [2025-06-08 09:37:12.914] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jun 08 09:37:12 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:12 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:12 volumio volumio[842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 08 09:37:12 volumio vtcs[1810]: [2025-06-08 09:37:12] [info] asio async_shutdown error: asio.misc:2 (End of file) Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:13 volumio volumio[842]: info: Currently active: webradio Jun 08 09:37:13 volumio volumio[842]: info: Stopping currently active service Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioStop Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::stop Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Jun 08 09:37:13 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::serviceStop Jun 08 09:37:13 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::serviceStop Jun 08 09:37:13 volumio volumio[842]: info: [1749368233029] ControllerWebradio::stop Jun 08 09:37:13 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 09:37:13 volumio volumio[842]: info: sendMpdCommand stop took 6 milliseconds Jun 08 09:37:13 volumio volumio[842]: info: TidalConnect Active Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:13 volumio volumio[842]: info: Currently active: webradio Jun 08 09:37:13 volumio volumio[842]: info: Setting Volatile state to tidalconnect Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio vtcs[1810]: [2025-06-08 09:37:13.437] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 Jun 08 09:37:13 volumio vtcs[1810]: [2025-06-08 09:37:13.437] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Jun 08 09:37:13 volumio volumio[842]: info: Pushing metadata Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:13 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:13 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:13 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:13 volumio volumio[842]: info: Pushing metadata Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:13 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:13 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:14 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:14 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:14 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:14 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:14 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:14 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:14 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:14 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:14 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:14 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:14 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:14 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:15 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:15 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:15 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:15 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:15 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:15 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:15 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:15 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:15 volumio vtcs[1810]: [2025-06-08 09:37:15.575] [tisoc] [error] [pcm_sink_alsa.cpp:114] Failed to open device volumio, error: Device or resource busy Jun 08 09:37:15 volumio vtcs[1810]: [2025-06-08 09:37:15.576] [tisoc] [error] [media_player_tidal.cpp:178] Could not open pcm sink: 1 Jun 08 09:37:15 volumio volumio[842]: info: Unknown command: notifyPlaybackError Jun 08 09:37:15 volumio vtcs[1810]: [2025-06-08 09:37:15] [info] asio async_shutdown error: asio.misc:2 (End of file) Jun 08 09:37:16 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:16 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:16 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:16 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:16 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:16 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:16 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:16 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:17 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:17 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:17 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:17 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:17 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:17 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:17 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:17 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:18 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:18 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:18 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:18 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:18 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:18 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:18 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:18 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:19 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:19 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:19 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:19 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:19 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:19 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:19 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:19 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:20 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:20 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:20 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:20 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:20 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:20 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:20 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:20 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Paused Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO MediaEngine: [0x7fb10388]: Pausing playback Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Pausing audio renderer Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] INFO SampleApp: Pausing playback Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.313 [1743.1743] ERROR AudioRenderer: [0x7fb105a8]: Playback error occurred Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.314 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending PlaybackError message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.314 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.314 [1743.1743] INFO SampleApp: Playback state changed: Stopped Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.314 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming ended event Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.314 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.314 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.314 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.318 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:21 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:21 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Playing Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: 0 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: 5.2 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: TID: 50826259, QID: 6, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: TID: 4529863, QID: 7, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO MediaEngine: [0x7fb10388]: Reseting Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO MediaEngine: [0x7fb10388]: Stopping playback, clearing tracks Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO MediaEngine: [0x7fb10388]: Initiating playback Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Reseting audio renderer Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting current track: 50826259, initial offset: 0ms Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 48]: Disposing of audio stream Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.364 [1743.1743] INFO ContentFetcher: [0x7fe19b90]: Canceling download Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.368 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 50 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.368 [1743.1743] INFO HttpDownloader: [0x7ff61218]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9361e73214d28728e6723d34538a97a4&request_ts=1749368241&track_id=50826259 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.368 [1743.1743] INFO HttpDownloader: [0x7fb49370]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=50826259 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.368 [1743.1743] INFO HttpDownloader: [0x7fc06d70]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=498ef54d2b0d01a1bdb057d35e19b5dc&request_ts=1749368241&track_id=50826259 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.368 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 50]: Running audio stream Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.369 [1743.1743] INFO SampleApp: [Stream 48]: Audio stream has been disposed of Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.369 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting next track: 4529863 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.369 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 51 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.369 [1743.1743] INFO HttpDownloader: [0x7fcfb228]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=d110858bbb1497891168ae7049f31e98&request_ts=1749368241&track_id=4529863 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.369 [1743.1743] INFO HttpDownloader: [0x7fb5ae18]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=4529863 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.369 [1743.1743] INFO HttpDownloader: [0x7fc90008]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=fdd43fc8c0823be3ceca184b524740aa&request_ts=1749368241&track_id=4529863 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.370 [1743.1743] INFO MediaEngine: [0x7fb10388]: Waiting for current stream to start before starting audio renderer Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.426 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 51]: Metadata became available: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.426 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: Faut faire avec Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.426 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Gilbert Bécaud Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.426 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Gilbert Becaud (1984-1999) [2011 Remastered] [Deluxe version] Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.426 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/52/23/5099973172352_600.jpg Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.435 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 50]: Metadata became available: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.435 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: Lucky Ones Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.435 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Tom Grennan Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.435 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Lighting Matches (Deluxe) Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.435 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/ha/97/uqp47jvzc97ha_600.jpg Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.465 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 51]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=4529863&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371840&hmac=7_2XcCr02yizR2HR7FQXP_sfAUk Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.472 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 50]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=50826259&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371840&hmac=JyU--eS6LcRz1AW94K61KidAMY4 Jun 08 09:37:21 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:21 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:21 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:21 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.501 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 50]: stream information have been fetched Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.501 [1743.1743] INFO UrlAudioSource: [0x7fb14628]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=50826259&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371840&hmac=JyU--eS6LcRz1AW94K61KidAMY4 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.501 [1743.1743] INFO ContentFetcher: [0x7fc99a00]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=50826259&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371840&hmac=JyU--eS6LcRz1AW94K61KidAMY4, offset: 0 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.501 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Starting audio renderer, initial playback state: Playing Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.501 [1743.1743] INFO SampleApp: [Stream 50]: New audio stream (starting from 0ms) Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.503 [1743.1743] INFO SampleApp: [Stream 50]: Stream metadata became available: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.503 [1743.1743] INFO SampleApp: Title: Lucky Ones Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.503 [1743.1743] INFO SampleApp: Artist: Tom Grennan Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.503 [1743.1743] INFO SampleApp: Album: Lighting Matches (Deluxe) Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.503 [1743.1743] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ha/97/uqp47jvzc97ha_600.jpg Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: QobuzConnect: setActiveState() Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: QobuzConnect: Set Qobuz connect default volume to 100 Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: QobuzConnect: Stopping currently active service Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioStop Jun 08 09:37:21 volumio volumio[842]: info: CoreStateMachine::stop Jun 08 09:37:21 volumio volumio[842]: info: CoreStateMachine::serviceStop Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::serviceStop Jun 08 09:37:21 volumio volumio[842]: warn: QobuzConnect: funcVolumioStop() returned undefined in place of a Promise Jun 08 09:37:21 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:21 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:21 volumio vtcs[1810]: [2025-06-08 09:37:21.506] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:0 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.506 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 51]: stream information have been fetched Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.506 [1743.1743] INFO VolumeManager: [0x7fb107f0]: Setting new playback volume: 100 Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:21 volumio volumio[842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect Jun 08 09:37:21 volumio volumio[842]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Jun 08 09:37:21 volumio volumio[842]: info: QobuzConnect: setActiveState() completed, send playback confirmation Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.517 [1743.1743] INFO SampleApp: Initiating playback, initial state: Playing Jun 08 09:37:21 volumio volumio[842]: info: PlaybackInactive Jun 08 09:37:21 volumio volumio[842]: info: Device Session is_active: true Jun 08 09:37:21 volumio volumio[842]: info: DeviceInactive Jun 08 09:37:21 volumio volumio[842]: info: Relinquishing Volumio State Jun 08 09:37:21 volumio volumio[842]: verbose: UNSET VOLATILE: Service: undefined Jun 08 09:37:21 volumio volumio[842]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Jun 08 09:37:21 volumio volumio[842]: info: QobuzConnect: Received stop Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.519 [1743.1743] INFO SampleApp: Playback state changed: Playing Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.519 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming started event Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.519 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.519 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.519 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.519 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.519 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending FileAudioQualityChanged message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.520 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.520 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending DeviceAudioQualityChanged message Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.520 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:21 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:21 volumio volumio[842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:21 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:21 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.665 [1743.1743] INFO UrlAudioSource: [0x7fb14628]: Content info extracted Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.665 [1743.1743] INFO UrlAudioSource: [0x7fb14628]: Size: 42219852 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.665 [1743.7940] INFO AudioDecoder: [0x7fbdb578]: Stream info: Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.666 [1743.7940] INFO AudioDecoder: [0x7fbdb578]: Total samples: 9429756 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.666 [1743.7940] INFO AudioDecoder: [0x7fbdb578]: Sample rate: 44100 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.666 [1743.7940] INFO AudioDecoder: [0x7fbdb578]: Channel count: 2 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.666 [1743.7940] INFO AudioDecoder: [0x7fbdb578]: Bits per sample: 24 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.666 [1743.7940] INFO AudioDecoder: [0x7fbdb578]: Duration: 213826 Jun 08 09:37:21 volumio qobuz-connect[1743]: 20250608 09:37:21.666 [1743.1743] INFO UrlAudioSource: [0x7fb14628]: Audio properties have changed Jun 08 09:37:22 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:22 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:22 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:22 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:22 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:22 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:22 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:22 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:22 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.306 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Paused Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO MediaEngine: [0x7fb10388]: Pausing playback Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Pausing audio renderer Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO SampleApp: Pausing playback Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] ERROR AudioRenderer: [0x7fb105a8]: Playback error occurred Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending PlaybackError message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO SampleApp: Playback state changed: Stopped Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming ended event Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.307 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.317 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Playing Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: 0 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: 5.2 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: TID: 4529863, QID: 7, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: TID: 456648, QID: 8, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO MediaEngine: [0x7fb10388]: Reseting Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO MediaEngine: [0x7fb10388]: Stopping playback, clearing tracks Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO MediaEngine: [0x7fb10388]: Initiating playback Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Reseting audio renderer Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting current track: 4529863, initial offset: 0ms Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 50]: Disposing of audio stream Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.370 [1743.1743] INFO ContentFetcher: [0x7fc99a00]: Canceling download Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.374 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 52 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.374 [1743.1743] INFO HttpDownloader: [0x7fcfb228]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=0ff64a68ad3ec38dc410a9b7b5ea7b79&request_ts=1749368243&track_id=4529863 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.374 [1743.1743] INFO HttpDownloader: [0x7fe6f428]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=4529863 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.374 [1743.1743] INFO HttpDownloader: [0x7fe6e658]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=90678da135c4cc3597549f27844416ed&request_ts=1749368243&track_id=4529863 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.374 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 52]: Running audio stream Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.375 [1743.1743] INFO SampleApp: [Stream 50]: Audio stream has been disposed of Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.375 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting next track: 456648 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.375 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 53 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.375 [1743.1743] INFO HttpDownloader: [0x7fbefa20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=386744ade44986f770aa86dc9f883c35&request_ts=1749368243&track_id=456648 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.375 [1743.1743] INFO HttpDownloader: [0x7fbf19a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=456648 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.375 [1743.1743] INFO HttpDownloader: [0x7fbf4ac0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=37c31c3a809579bc81735ce1930be9ae&request_ts=1749368243&track_id=456648 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.376 [1743.1743] INFO MediaEngine: [0x7fb10388]: Waiting for current stream to start before starting audio renderer Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.478 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 53]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=456648&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371842&hmac=Z4kv0lcnjIGzlGooLKi6pvDGmtk Jun 08 09:37:23 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:23 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:23 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:23 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.507 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 52]: Metadata became available: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.507 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: Faut faire avec Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.507 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Gilbert Bécaud Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.507 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Gilbert Becaud (1984-1999) [2011 Remastered] [Deluxe version] Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.507 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/52/23/5099973172352_600.jpg Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.532 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 52]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=4529863&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371842&hmac=gfQHPh5Gj1Dvo2hwilqetsUEjew Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.542 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 53]: Metadata became available: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.542 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: I Was Made for Loving You Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.542 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Maria Mena Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.542 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Cause and Effect Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.542 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/58/71/0888880697158_600.jpg Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.551 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 53]: stream information have been fetched Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 52]: stream information have been fetched Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO UrlAudioSource: [0x7fb1cba0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=4529863&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371842&hmac=gfQHPh5Gj1Dvo2hwilqetsUEjew Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO ContentFetcher: [0x7fb20738]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=4529863&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371842&hmac=gfQHPh5Gj1Dvo2hwilqetsUEjew, offset: 0 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Starting audio renderer, initial playback state: Playing Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO SampleApp: [Stream 52]: New audio stream (starting from 0ms) Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO SampleApp: [Stream 52]: Stream metadata became available: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO SampleApp: Title: Faut faire avec Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO SampleApp: Artist: Gilbert Bécaud Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO SampleApp: Album: Gilbert Becaud (1984-1999) [2011 Remastered] [Deluxe version] Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.570 [1743.1743] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/52/23/5099973172352_600.jpg Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.572 [1743.1743] INFO VolumeManager: [0x7fb107f0]: Setting new playback volume: 100 Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: QobuzConnect: setActiveState() Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: QobuzConnect: Set Qobuz connect default volume to 100 Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: QobuzConnect: Stopping currently active service Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioStop Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::stop Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::serviceStop Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::serviceStop Jun 08 09:37:23 volumio volumio[842]: info: [1749368243575] ControllerWebradio::stop Jun 08 09:37:23 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 09:37:23 volumio volumio[842]: info: sendMpdCommand stop took 6 milliseconds Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CorePlayQueue::getTrack 0 Jun 08 09:37:23 volumio volumio[842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect Jun 08 09:37:23 volumio volumio[842]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Jun 08 09:37:23 volumio volumio[842]: info: QobuzConnect: setActiveState() completed, send playback confirmation Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.585 [1743.1743] INFO SampleApp: Initiating playback, initial state: Playing Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.587 [1743.1743] INFO SampleApp: Playback state changed: Playing Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.587 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming started event Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.587 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.587 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.587 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.587 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.587 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending FileAudioQualityChanged message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.588 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.588 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending DeviceAudioQualityChanged message Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.588 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:23 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:23 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:23 volumio volumio[842]: info: [LastFM] Current track has sufficient metadata: title (This Bitter Earth (Live)) and artist (Jeff Goldblum & The Mildred Snitzer Orchestra) passed on explicitly Jun 08 09:37:23 volumio volumio[842]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jun 08 09:37:23 volumio volumio[842]: info: [LastFM] Current track has sufficient metadata: title (Faut faire avec) and artist (Gilbert Bécaud) passed on explicitly Jun 08 09:37:23 volumio volumio[842]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.896 [1743.1743] INFO UrlAudioSource: [0x7fb1cba0]: Content info extracted Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.896 [1743.1743] INFO UrlAudioSource: [0x7fb1cba0]: Size: 22330302 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.902 [1743.7941] INFO AudioDecoder: [0x7fbd0e38]: Stream info: Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.902 [1743.7941] INFO AudioDecoder: [0x7fbd0e38]: Total samples: 8996400 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.902 [1743.7941] INFO AudioDecoder: [0x7fbd0e38]: Sample rate: 44100 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.902 [1743.7941] INFO AudioDecoder: [0x7fbd0e38]: Channel count: 2 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.902 [1743.7941] INFO AudioDecoder: [0x7fbd0e38]: Bits per sample: 16 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.902 [1743.7941] INFO AudioDecoder: [0x7fbd0e38]: Duration: 204000 Jun 08 09:37:23 volumio qobuz-connect[1743]: 20250608 09:37:23.902 [1743.1743] INFO UrlAudioSource: [0x7fb1cba0]: Audio properties have changed Jun 08 09:37:24 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:24 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:24 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:24 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:24 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:24 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:24 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:24 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:25 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:25 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:25 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:25 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:25 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:25 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:25 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:25 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:26 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:26 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:26 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:26 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:26 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:26 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:26 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:26 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:27 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:27 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:27 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:27 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:27 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:27 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:27 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:27 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:28 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:28 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:28 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:28 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:28 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:28 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:28 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:28 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:29 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:29 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:29 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:29 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:29 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:29 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:29 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:29 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:30 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:30 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Paused Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO MediaEngine: [0x7fb10388]: Pausing playback Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Pausing audio renderer Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO SampleApp: Pausing playback Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] ERROR AudioRenderer: [0x7fb105a8]: Playback error occurred Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending PlaybackError message Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO SampleApp: Playback state changed: Stopped Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming ended event Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.368 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.369 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.369 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.369 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:30 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:30 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Playing Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: 0 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: 5.2 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: TID: 456648, QID: 8, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: TID: 1282848, QID: 9, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO MediaEngine: [0x7fb10388]: Reseting Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO MediaEngine: [0x7fb10388]: Stopping playback, clearing tracks Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO MediaEngine: [0x7fb10388]: Initiating playback Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Reseting audio renderer Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.421 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.422 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting current track: 456648, initial offset: 0ms Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.422 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.422 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 52]: Disposing of audio stream Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.422 [1743.1743] INFO ContentFetcher: [0x7fb20738]: Canceling download Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:30 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.426 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 54 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.427 [1743.1743] INFO HttpDownloader: [0x7fce8fd8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=f4746441a680550ec4856430e3051652&request_ts=1749368250&track_id=456648 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.427 [1743.1743] INFO HttpDownloader: [0x7fb139b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=456648 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.427 [1743.1743] INFO HttpDownloader: [0x7fb49370]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4a7e7c7c77e61d082f8b5d3e74d7ff65&request_ts=1749368250&track_id=456648 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.427 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 54]: Running audio stream Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.428 [1743.1743] INFO SampleApp: [Stream 52]: Audio stream has been disposed of Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.428 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting next track: 1282848 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.428 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 55 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.428 [1743.1743] INFO HttpDownloader: [0x7fd19a80]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=0b46988bb217ee12fcd0b61d05236f34&request_ts=1749368250&track_id=1282848 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.428 [1743.1743] INFO HttpDownloader: [0x7ffef580]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=1282848 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.428 [1743.1743] INFO HttpDownloader: [0x7fed4c08]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=356507fcbf189b4a736daa01329ec1c8&request_ts=1749368250&track_id=1282848 Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.429 [1743.1743] INFO MediaEngine: [0x7fb10388]: Waiting for current stream to start before starting audio renderer Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.492 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 54]: Metadata became available: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.492 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: I Was Made for Loving You Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.492 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Maria Mena Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.492 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Cause and Effect Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.492 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/58/71/0888880697158_600.jpg Jun 08 09:37:30 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:30 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:30 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:30 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.533 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 54]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=456648&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371849&hmac=iyw0Z-l0GrPKVQoZjRFotyML-64 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 54]: stream information have been fetched Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO UrlAudioSource: [0x7fb1cba0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=456648&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371849&hmac=iyw0Z-l0GrPKVQoZjRFotyML-64 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO ContentFetcher: [0x7fcfa2b8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=456648&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371849&hmac=iyw0Z-l0GrPKVQoZjRFotyML-64, offset: 0 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Starting audio renderer, initial playback state: Playing Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO SampleApp: [Stream 54]: New audio stream (starting from 0ms) Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO SampleApp: [Stream 54]: Stream metadata became available: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO SampleApp: Title: I Was Made for Loving You Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO SampleApp: Artist: Maria Mena Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO SampleApp: Album: Cause and Effect Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.576 [1743.1743] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/58/71/0888880697158_600.jpg Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.577 [1743.1743] INFO SampleApp: Initiating playback, initial state: Playing Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.579 [1743.1743] INFO SampleApp: Playback state changed: Playing Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.579 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming started event Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.579 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.579 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.579 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:30 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.580 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.580 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending FileAudioQualityChanged message Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.580 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.581 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 55]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=1282848&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371850&hmac=6u2bSQ2fDs3xbBD5VPtgqKd7bDo Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:30 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:30 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:30 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:30 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:30 volumio volumio[842]: info: [LastFM] Current track has sufficient metadata: title (I Was Made for Loving You) and artist (Maria Mena) passed on explicitly Jun 08 09:37:30 volumio volumio[842]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.648 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 55]: Metadata became available: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.648 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: Smile Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.648 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Etta Cameron Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.648 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Etta Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.648 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/14/96/0843436049614_600.jpg Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.648 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 55]: stream information have been fetched Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.698 [1743.1743] INFO UrlAudioSource: [0x7fb1cba0]: Content info extracted Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.698 [1743.1743] INFO UrlAudioSource: [0x7fb1cba0]: Size: 27302075 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.703 [1743.7958] INFO AudioDecoder: [0x7fbd6680]: Stream info: Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.703 [1743.7958] INFO AudioDecoder: [0x7fbd6680]: Total samples: 12096336 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.703 [1743.7958] INFO AudioDecoder: [0x7fbd6680]: Sample rate: 44100 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.703 [1743.7958] INFO AudioDecoder: [0x7fbd6680]: Channel count: 2 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.703 [1743.7958] INFO AudioDecoder: [0x7fbd6680]: Bits per sample: 16 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.703 [1743.7958] INFO AudioDecoder: [0x7fbd6680]: Duration: 274293 Jun 08 09:37:30 volumio qobuz-connect[1743]: 20250608 09:37:30.703 [1743.1743] INFO UrlAudioSource: [0x7fb1cba0]: Audio properties have changed Jun 08 09:37:31 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:31 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:31 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:31 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:31 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:31 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:31 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:31 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:32 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:32 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:32 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:32 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:32 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:32 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:32 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:32 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:33 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:33 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Paused Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO MediaEngine: [0x7fb10388]: Pausing playback Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Pausing audio renderer Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] INFO SampleApp: Pausing playback Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.368 [1743.1743] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.369 [1743.1743] ERROR AudioRenderer: [0x7fb105a8]: Playback error occurred Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.369 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending PlaybackError message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.369 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.369 [1743.1743] INFO SampleApp: Playback state changed: Stopped Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.369 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming ended event Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.369 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.369 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.369 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.370 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:33 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:33 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.415 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.415 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Playing Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.415 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: 0 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.415 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: 5.2 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.415 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: TID: 1282848, QID: 9, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: TID: 100653713, QID: 10, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO MediaEngine: [0x7fb10388]: Reseting Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO MediaEngine: [0x7fb10388]: Stopping playback, clearing tracks Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO MediaEngine: [0x7fb10388]: Initiating playback Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Reseting audio renderer Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting current track: 1282848, initial offset: 0ms Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 54]: Disposing of audio stream Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.416 [1743.1743] INFO ContentFetcher: [0x7fcfa2b8]: Canceling download Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:33 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.422 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 56 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.422 [1743.1743] INFO HttpDownloader: [0x7fce8fd8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=38fb7ea980630be60b3ac3d789f9cca0&request_ts=1749368253&track_id=1282848 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.422 [1743.1743] INFO HttpDownloader: [0x7fb251f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=1282848 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.422 [1743.1743] INFO HttpDownloader: [0x7fd4d1d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=964380075c7e249f795159a07a041310&request_ts=1749368253&track_id=1282848 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.422 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 56]: Running audio stream Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.423 [1743.1743] INFO SampleApp: [Stream 54]: Audio stream has been disposed of Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.423 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting next track: 100653713 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.423 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 57 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.423 [1743.1743] INFO HttpDownloader: [0x7fd1a5f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=f536b39ac507e5613c3f737fecb69075&request_ts=1749368253&track_id=100653713 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.423 [1743.1743] INFO HttpDownloader: [0x7fd4b040]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=100653713 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.423 [1743.1743] INFO HttpDownloader: [0x80026fb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e5de530a9f62a477e3676faf6956beaa&request_ts=1749368253&track_id=100653713 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.425 [1743.1743] INFO MediaEngine: [0x7fb10388]: Waiting for current stream to start before starting audio renderer Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.485 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 56]: Metadata became available: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.485 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: Smile Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.485 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Etta Cameron Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.485 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Etta Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.485 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/14/96/0843436049614_600.jpg Jun 08 09:37:33 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:33 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:33 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:33 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.525 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 56]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=1282848&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371852&hmac=-MqgLdr8tIgJzF7PsH_gOAfcdM4 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.555 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 57]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=100653713&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371852&hmac=AvamsFsvQnAgIpmA0F5zieJU6Bw Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.571 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 57]: stream information have been fetched Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.636 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 57]: Metadata became available: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.636 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: This Bitter Earth Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.636 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Veronica Swift Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.636 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: This Bitter Earth Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.636 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/wb/34/unzhytsa934wb_600.jpg Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 56]: stream information have been fetched Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO UrlAudioSource: [0x7fc05e60]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=1282848&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371852&hmac=-MqgLdr8tIgJzF7PsH_gOAfcdM4 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO ContentFetcher: [0x7fcfb0c0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=1282848&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371852&hmac=-MqgLdr8tIgJzF7PsH_gOAfcdM4, offset: 0 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Starting audio renderer, initial playback state: Playing Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO SampleApp: [Stream 56]: New audio stream (starting from 0ms) Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO SampleApp: [Stream 56]: Stream metadata became available: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO SampleApp: Title: Smile Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO SampleApp: Artist: Etta Cameron Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO SampleApp: Album: Etta Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.653 [1743.1743] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/14/96/0843436049614_600.jpg Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.654 [1743.1743] INFO SampleApp: Initiating playback, initial state: Playing Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.656 [1743.1743] INFO SampleApp: Playback state changed: Playing Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.656 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming started event Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.656 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.656 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.656 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.657 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:33 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.657 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending FileAudioQualityChanged message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.657 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.657 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending DeviceAudioQualityChanged message Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.657 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:33 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:33 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:33 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:33 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:33 volumio volumio[842]: info: [LastFM] Current track has sufficient metadata: title (Smile) and artist (Etta Cameron) passed on explicitly Jun 08 09:37:33 volumio volumio[842]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.1743] INFO UrlAudioSource: [0x7fc05e60]: Content info extracted Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.1743] INFO UrlAudioSource: [0x7fc05e60]: Size: 120218242 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.7959] INFO AudioDecoder: [0x800e97f0]: Stream info: Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.7959] INFO AudioDecoder: [0x800e97f0]: Total samples: 32504674 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.7959] INFO AudioDecoder: [0x800e97f0]: Sample rate: 96000 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.7959] INFO AudioDecoder: [0x800e97f0]: Channel count: 2 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.7959] INFO AudioDecoder: [0x800e97f0]: Bits per sample: 24 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.7959] INFO AudioDecoder: [0x800e97f0]: Duration: 338590 Jun 08 09:37:33 volumio qobuz-connect[1743]: 20250608 09:37:33.766 [1743.1743] INFO UrlAudioSource: [0x7fc05e60]: Audio properties have changed Jun 08 09:37:34 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:34 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:34 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:34 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:34 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:34 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:34 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:34 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Playing Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: 0 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: 5.2 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: TID: 456648, QID: 8, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: TID: 1282848, QID: 9, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO MediaEngine: [0x7fb10388]: Stopping playback, clearing tracks Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO MediaEngine: [0x7fb10388]: Initiating playback Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Stopping audio renderer Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO SampleApp: Stopping playback Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 56]: Disposing of audio stream Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.250 [1743.1743] INFO ContentFetcher: [0x7fcfb0c0]: Canceling download Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.254 [1743.1743] INFO SampleApp: Playback state changed: Stopped Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming ended event Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO SampleApp: [Stream 56]: Audio stream has been disposed of Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting current track: 456648, initial offset: 0ms Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 58 Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:35 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO HttpDownloader: [0x7fc02730]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=702e0a06e65a048f62cd364fc0319f2e&request_ts=1749368255&track_id=456648 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO HttpDownloader: [0x7fcfb3b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=456648 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO HttpDownloader: [0x7fe6e658]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e041371bd1a0fb7b01dbf430f4643ce1&request_ts=1749368255&track_id=456648 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 58]: Running audio stream Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting next track: 1282848 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 59 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO HttpDownloader: [0x7fb49370]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6aaff70b275269b8f67fe9d156faf9f9&request_ts=1749368255&track_id=1282848 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.255 [1743.1743] INFO HttpDownloader: [0x7fc8eb20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=1282848 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.256 [1743.1743] INFO HttpDownloader: [0x7ffab230]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=9b0532fb00eb6308fc2da0b5513d8325&request_ts=1749368255&track_id=1282848 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.257 [1743.1743] INFO MediaEngine: [0x7fb10388]: Waiting for current stream to start before starting audio renderer Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:35 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.325 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 58]: Metadata became available: Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.325 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: I Was Made for Loving You Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.325 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Maria Mena Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.325 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Cause and Effect Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.325 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/58/71/0888880697158_600.jpg Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.359 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 58]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=456648&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371854&hmac=Ibk4BjMoM54G51keqkrehUhZSA0 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.371 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 59]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=1282848&fmt=7&profile=raw&app_id=174516466&cid=2817022&etsp=1749371854&hmac=bfyKEYRZv-tORrTpimuJCGYhoAk Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.378 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 59]: Metadata became available: Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.378 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: Smile Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.378 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Etta Cameron Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.378 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Etta Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.378 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/14/96/0843436049614_600.jpg Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 58]: stream information have been fetched Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO UrlAudioSource: [0x7fbde198]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=456648&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371854&hmac=Ibk4BjMoM54G51keqkrehUhZSA0 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO ContentFetcher: [0x7fcfa2b8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=456648&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371854&hmac=Ibk4BjMoM54G51keqkrehUhZSA0, offset: 0 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Starting audio renderer, initial playback state: Playing Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO SampleApp: [Stream 58]: New audio stream (starting from 0ms) Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO SampleApp: [Stream 58]: Stream metadata became available: Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO SampleApp: Title: I Was Made for Loving You Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO SampleApp: Artist: Maria Mena Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO SampleApp: Album: Cause and Effect Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.401 [1743.1743] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/58/71/0888880697158_600.jpg Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.402 [1743.1743] INFO SampleApp: Initiating playback, initial state: Playing Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO SampleApp: Playback state changed: Playing Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming started event Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending FileAudioQualityChanged message Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending DeviceAudioQualityChanged message Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.404 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:35 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:35 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:35 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:35 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:35 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:35 volumio volumio[842]: info: [LastFM] Current track has sufficient metadata: title (I Was Made for Loving You) and artist (Maria Mena) passed on explicitly Jun 08 09:37:35 volumio volumio[842]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.470 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.473 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 59]: stream information have been fetched Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.1743] INFO UrlAudioSource: [0x7fbde198]: Content info extracted Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.1743] INFO UrlAudioSource: [0x7fbde198]: Size: 27302075 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.7963] INFO AudioDecoder: [0x7fbd7ba0]: Stream info: Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.7963] INFO AudioDecoder: [0x7fbd7ba0]: Total samples: 12096336 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.7963] INFO AudioDecoder: [0x7fbd7ba0]: Sample rate: 44100 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.7963] INFO AudioDecoder: [0x7fbd7ba0]: Channel count: 2 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.7963] INFO AudioDecoder: [0x7fbd7ba0]: Bits per sample: 16 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.7963] INFO AudioDecoder: [0x7fbd7ba0]: Duration: 274293 Jun 08 09:37:35 volumio qobuz-connect[1743]: 20250608 09:37:35.493 [1743.1743] INFO UrlAudioSource: [0x7fbde198]: Audio properties have changed Jun 08 09:37:35 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:35 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:35 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:35 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:37:36 volumio volumio[842]: info: CoreStateMachine::getQueue Jun 08 09:37:36 volumio volumio[842]: info: CorePlayQueue::getQueue Jun 08 09:37:36 volumio lircd[1360]: lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:36 volumio lircd[1360]: lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:36 volumio lircd-0.10.1[1360]: Error: could not get file information for /dev/lirc0 Jun 08 09:37:36 volumio lircd-0.10.1[1360]: default_init(): No such file or directory Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Playing Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: 0 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: 5.2 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: TID: 4529863, QID: 7, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: TID: 456648, QID: 8, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO MediaEngine: [0x7fb10388]: Stopping playback, clearing tracks Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO MediaEngine: [0x7fb10388]: Initiating playback Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Stopping audio renderer Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.726 [1743.1743] INFO SampleApp: Stopping playback Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.727 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.727 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 58]: Disposing of audio stream Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.727 [1743.1743] INFO ContentFetcher: [0x7fcfa2b8]: Canceling download Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO SampleApp: Playback state changed: Stopped Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming ended event Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:36 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO SampleApp: [Stream 58]: Audio stream has been disposed of Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting current track: 4529863, initial offset: 0ms Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 60 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO HttpDownloader: [0x802c51f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4230720641f2b08f7149d453235cb664&request_ts=1749368256&track_id=4529863 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO HttpDownloader: [0x7fcfb228]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=4529863 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO HttpDownloader: [0x7fb139b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=d9d5875e7e6635fdf1a5aaabd1e54f59&request_ts=1749368256&track_id=4529863 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.731 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 60]: Running audio stream Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.732 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.733 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting next track: 456648 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.733 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 61 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.733 [1743.1743] INFO HttpDownloader: [0x7fc884b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=0aebe195c791fed50d2bed17cad885d3&request_ts=1749368256&track_id=456648 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.733 [1743.1743] INFO HttpDownloader: [0x7fcf6840]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=456648 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.733 [1743.1743] INFO HttpDownloader: [0x7fc60e68]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=804d74a61731ce828aba8139cb98402c&request_ts=1749368256&track_id=456648 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.734 [1743.1743] INFO MediaEngine: [0x7fb10388]: Waiting for current stream to start before starting audio renderer Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:36 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.791 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 61]: Metadata became available: Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.791 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: I Was Made for Loving You Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.791 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Maria Mena Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.791 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Cause and Effect Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.791 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/58/71/0888880697158_600.jpg Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.870 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 60]: stream information have been fetched Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.872 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 61]: stream information have been fetched Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.875 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 60]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=4529863&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371856&hmac=I6J77GS_atrtoqm8t6WxnhrCMiU Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.875 [1743.1743] INFO UrlAudioSource: [0x7fbd8278]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=4529863&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371856&hmac=I6J77GS_atrtoqm8t6WxnhrCMiU Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.875 [1743.1743] INFO ContentFetcher: [0x800482d8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=4529863&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371856&hmac=I6J77GS_atrtoqm8t6WxnhrCMiU, offset: 0 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.875 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Starting audio renderer, initial playback state: Playing Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.875 [1743.1743] INFO SampleApp: [Stream 60]: New audio stream (starting from 0ms) Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.876 [1743.1743] INFO SampleApp: Initiating playback, initial state: Playing Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.877 [1743.1743] INFO SampleApp: Playback state changed: Playing Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.877 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming started event Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.878 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.878 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.878 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.878 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending FileAudioQualityChanged message Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.878 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:36 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:36 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:36 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::servicePushState Jun 08 09:37:36 volumio volumio[842]: info: CoreStateMachine::pushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Jun 08 09:37:36 volumio volumio[842]: info: CoreCommandRouter::volumioGetState Jun 08 09:37:36 volumio volumio[842]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jun 08 09:37:36 volumio volumio[842]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 09:37:36 volumio volumio[842]: TypeError: Cannot read property 'indexOf' of undefined Jun 08 09:37:36 volumio volumio[842]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jun 08 09:37:36 volumio volumio[842]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jun 08 09:37:36 volumio volumio[842]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jun 08 09:37:36 volumio volumio[842]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 08 09:37:36 volumio volumio[842]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jun 08 09:37:36 volumio volumio[842]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jun 08 09:37:36 volumio volumio[842]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 08 09:37:36 volumio volumio[842]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 08 09:37:36 volumio volumio[842]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jun 08 09:37:36 volumio volumio[842]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 08 09:37:36 volumio volumio[842]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jun 08 09:37:36 volumio volumio[842]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jun 08 09:37:36 volumio volumio[842]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jun 08 09:37:36 volumio volumio[842]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 08 09:37:36 volumio volumio[842]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 08 09:37:36 volumio volumio[842]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jun 08 09:37:36 volumio volumio[842]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.948 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.949 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 61]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2837636&eid=456648&fmt=6&profile=raw&app_id=174516466&cid=2817022&etsp=1749371856&hmac=iH8uni7h6yaD3Q9enQWBL8nvKTo Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.1743] INFO UrlAudioSource: [0x7fbd8278]: Content info extracted Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.1743] INFO UrlAudioSource: [0x7fbd8278]: Size: 22330302 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.7972] INFO AudioDecoder: [0x7fb5f7a8]: Stream info: Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.7972] INFO AudioDecoder: [0x7fb5f7a8]: Total samples: 8996400 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.7972] INFO AudioDecoder: [0x7fb5f7a8]: Sample rate: 44100 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.7972] INFO AudioDecoder: [0x7fb5f7a8]: Channel count: 2 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.7972] INFO AudioDecoder: [0x7fb5f7a8]: Bits per sample: 16 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.7972] INFO AudioDecoder: [0x7fb5f7a8]: Duration: 204000 Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.984 [1743.1743] INFO UrlAudioSource: [0x7fbd8278]: Audio properties have changed Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 60]: Metadata became available: Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: Faut faire avec Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Gilbert Bécaud Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Gilbert Becaud (1984-1999) [2011 Remastered] [Deluxe version] Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/52/23/5099973172352_600.jpg Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO SampleApp: [Stream 60]: Stream metadata became available: Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO SampleApp: Title: Faut faire avec Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO SampleApp: Artist: Gilbert Bécaud Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO SampleApp: Album: Gilbert Becaud (1984-1999) [2011 Remastered] [Deluxe version] Jun 08 09:37:36 volumio qobuz-connect[1743]: 20250608 09:37:36.993 [1743.1743] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/52/23/5099973172352_600.jpg Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Received SetState message: Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playing state: Playing Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Playback position: 0 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Queue version: 5.2 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Current track: TID: 50826259, QID: 6, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Next track: TID: 4529863, QID: 7, Context UUID: 6a983433-6a30-482b-af71-f1a81db6026f Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO MediaEngine: [0x7fb10388]: Stopping playback, clearing tracks Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO MediaEngine: [0x7fb10388]: Initiating playback Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO AudioRenderer: [0x7fb105a8]: Stopping audio renderer Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.236 [1743.1743] INFO SampleApp: Stopping playback Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.237 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.237 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 60]: Disposing of audio stream Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.237 [1743.1743] INFO ContentFetcher: [0x800482d8]: Canceling download Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.242 [1743.1743] INFO SampleApp: Playback state changed: Stopped Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.242 [1743.1743] INFO StreamingEventReporter: [0x7fb10ee0]: Reporting streaming ended event Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.242 [1743.1743] INFO HttpDownloader: [0x7fb10f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.242 [1743.1743] INFO ProtocolHandler: [0x7fb10ac8]: Need to send state update Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.242 [1743.1743] INFO QConnectMessageSender: [0x7fc06c98]: Sending StateUpdated message Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.242 [1743.1743] INFO SampleApp: [Stream 60]: Audio stream has been disposed of Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.242 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting current track: 50826259, initial offset: 0ms Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.242 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Clearing all streams Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.243 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 62 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.243 [1743.1743] INFO HttpDownloader: [0x7fb51a30]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b6604a9e07c78282200f7fa4f41c345f&request_ts=1749368257&track_id=50826259 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.243 [1743.1743] INFO HttpDownloader: [0x7fe6e658]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=50826259 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.243 [1743.1743] INFO HttpDownloader: [0x7fe6f428]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=c1c052cd9d5aa1e21d47e713e79c8b35&request_ts=1749368257&track_id=50826259 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.243 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 62]: Running audio stream Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.243 [1743.1743] INFO QwspMessageSender: [0x7fc044e8]: Sending Payload message Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.244 [1743.1743] INFO MediaEngine: [0x7fb10388]: Setting next track: 4529863 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.244 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: New stream: 63 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.244 [1743.1743] INFO HttpDownloader: [0x7fed8ed8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=cae8c0c444838b99a87378afcccda62d&request_ts=1749368257&track_id=4529863 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.244 [1743.1743] INFO HttpDownloader: [0x7fc12dd0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=4529863 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.244 [1743.1743] INFO HttpDownloader: [0x7fc8b470]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=26edafb126a10f3f724c2ad867eefd8a&request_ts=1749368257&track_id=4529863 Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.244 [1743.1743] INFO MediaEngine: [0x7fb10388]: Waiting for current stream to start before starting audio renderer Jun 08 09:37:37 volumio sudo[7974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 09:36 Jun 08 09:37:37 volumio sudo[7974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.307 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: [Stream 63]: Metadata became available: Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.307 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Title: Faut faire avec Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.307 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Artist: Gilbert Bécaud Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.307 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album: Gilbert Becaud (1984-1999) [2011 Remastered] [Deluxe version] Jun 08 09:37:37 volumio qobuz-connect[1743]: 20250608 09:37:37.307 [1743.1743] INFO AudioStreamManager: [0x7fb10470]: Album art URL: https://static.qobuz.com/images/covers/52/23/5099973172352_600.jpg PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 03 Jun 2025 10:59:11 AM CEST" VOLUMIO_VERSION="3.813" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="4ab7906c45503ee0ba719461ff7118b6"