-- Logs begin at Fri 2025-09-05 22:18:04 JST, end at Mon 2025-09-15 07:49:24 JST. -- Sep 15 07:48:00 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:00 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:01 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:01 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:02 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:02 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:03 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:03 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:04 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:04 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:05 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:05 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:06 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:06 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:07 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:07 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:08 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:08 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:09 rivo-toshi qobuz-connect[4040]: 20250915 07:48:09.284 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:48:09 rivo-toshi qobuz-connect[4040]: 20250915 07:48:09.284 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:48:09 rivo-toshi qobuz-connect[4040]: 20250915 07:48:09.284 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:48:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:48:09 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:48:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:48:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:48:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:09 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:48:09 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:48:09 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:48:09 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:11 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:11 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:12 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:12 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:13 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:13 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:14 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:14 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:15 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:15 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:15 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:15 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:17 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:17 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:18 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:18 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:19 rivo-toshi qobuz-connect[4040]: 20250915 07:48:19.284 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:48:19 rivo-toshi qobuz-connect[4040]: 20250915 07:48:19.284 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:48:19 rivo-toshi qobuz-connect[4040]: 20250915 07:48:19.284 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:48:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:48:19 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:48:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:48:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:48:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:19 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:48:19 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:48:19 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:48:19 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:20 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:20 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:21 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:21 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:21 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:21 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:22 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:24 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:24 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:25 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:25 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:26 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:26 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:28 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:28 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:28 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:28 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:29 rivo-toshi qobuz-connect[4040]: 20250915 07:48:29.284 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:48:29 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:29 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:48:29 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:48:29 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:48:29 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:48:29 rivo-toshi qobuz-connect[4040]: 20250915 07:48:29.289 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:48:29 rivo-toshi qobuz-connect[4040]: 20250915 07:48:29.290 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:48:29 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:29 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:48:29 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:48:29 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:48:29 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:29 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:30 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:30 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:31 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:31 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:33 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:33 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:34 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:34 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:35 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:35 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:36 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:36 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:38 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:38 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:39 rivo-toshi qobuz-connect[4040]: 20250915 07:48:39.284 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:48:39 rivo-toshi qobuz-connect[4040]: 20250915 07:48:39.284 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:48:39 rivo-toshi qobuz-connect[4040]: 20250915 07:48:39.284 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:48:39 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:39 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:48:39 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:48:39 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:48:39 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:48:39 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:39 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:48:39 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:48:39 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:48:39 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:39 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:39 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:39 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:40 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:40 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:41 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:41 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:42 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:42 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:44 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:44 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:45 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:45 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:46 rivo-toshi volumiologrotate[2827]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Sep 15 07:48:46 rivo-toshi volumiologrotate[2827]: ls: cannot access 'TOSHI': No such file or directory Sep 15 07:48:46 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:46 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:47 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:47 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:48 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:48 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:49 rivo-toshi qobuz-connect[4040]: 20250915 07:48:49.284 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:48:49 rivo-toshi qobuz-connect[4040]: 20250915 07:48:49.285 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:48:49 rivo-toshi qobuz-connect[4040]: 20250915 07:48:49.285 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:48:49 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:49 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:48:49 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:48:49 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:48:49 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:48:49 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:49 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:48:49 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:48:49 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:48:49 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:49 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:50 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:50 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:51 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:51 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:52 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:52 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:53 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:53 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:54 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:54 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:56 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:56 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:57 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:57 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:58 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:58 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:59 rivo-toshi qobuz-connect[4040]: 20250915 07:48:59.285 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:48:59 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:59 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:48:59 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:48:59 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:48:59 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:48:59 rivo-toshi qobuz-connect[4040]: 20250915 07:48:59.289 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:48:59 rivo-toshi qobuz-connect[4040]: 20250915 07:48:59.289 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:48:59 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:48:59 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:48:59 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:48:59 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:48:59 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:48:59 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:00 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:00 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:02 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:02 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:03 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:03 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:04 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:04 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:05 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:05 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:06 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:06 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:07 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:07 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:07 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:07 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:09 rivo-toshi qobuz-connect[4040]: 20250915 07:49:09.285 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:49:09 rivo-toshi qobuz-connect[4040]: 20250915 07:49:09.286 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:49:09 rivo-toshi qobuz-connect[4040]: 20250915 07:49:09.286 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:49:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:09 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:49:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:09 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:09 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:09 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:49:09 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:09 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:09 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:10 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:10 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:10 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:11 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:11 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:12 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:12 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:13 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:13 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:15 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:15 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:16 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:16 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:17 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:17 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:18 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:18 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:19 rivo-toshi qobuz-connect[4040]: 20250915 07:49:19.286 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:49:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:19 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:49:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:19 rivo-toshi qobuz-connect[4040]: 20250915 07:49:19.291 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:49:19 rivo-toshi qobuz-connect[4040]: 20250915 07:49:19.291 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:49:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:19 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:19 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:19 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:49:19 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:19 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:21 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:21 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.418 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Received SetState message: Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.418 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Playing state: Playing Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.419 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Playback position: 0 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.419 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Queue version: 1.2 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.419 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Current track: TID: 93470299, QID: 3, Context UUID: d1c2797f-bd17-4ba4-ba19-55c750daaf3b Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.419 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Next track: TID: 93470300, QID: 4, Context UUID: d1c2797f-bd17-4ba4-ba19-55c750daaf3b Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.419 [4040.4040] INFO MediaEngine: [0xac8e4df8]: Stopping playback, clearing tracks Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.419 [4040.4040] INFO MediaEngine: [0xac8e4df8]: Initiating playback Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.419 [4040.4040] INFO AudioRenderer: [0xac8e5018]: Stopping audio renderer Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.419 [4040.4040] INFO SampleApp: Stopping playback Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.444 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Clearing all streams Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.444 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: [Stream 1]: Disposing of audio stream Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.444 [4040.4040] INFO ContentFetcher: [0xac8ef228]: Canceling download Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.452 [4040.4040] INFO SampleApp: Playback state changed: Stopped Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.452 [4040.4040] INFO StreamingEventReporter: [0xac8e5950]: Reporting streaming ended event Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.452 [4040.4040] INFO HttpDownloader: [0xac8e59a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.453 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.453 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.453 [4040.4040] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.453 [4040.4040] INFO MediaEngine: [0xac8e4df8]: Setting current track: 93470299, initial offset: 0ms Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.453 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Clearing all streams Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.453 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: New stream: 3 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.453 [4040.4040] INFO HttpDownloader: [0xacabd570]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=5ad5a4ee32cbbdf71bc16a4259d83dfa&request_ts=1757890162&track_id=93470299 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.453 [4040.4040] INFO HttpDownloader: [0xaca87c00]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=93470299 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.454 [4040.4040] INFO HttpDownloader: [0xac9ce170]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=842caf0e280f84f6812af5f74cc55038&request_ts=1757890162&track_id=93470299 Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.454 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: [Stream 3]: Running audio stream Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.454 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:22 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.463 [4040.4040] INFO MediaEngine: [0xac8e4df8]: Setting next track: 93470300 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.463 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: New stream: 4 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.463 [4040.4040] INFO HttpDownloader: [0xac9d2ce0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=d252e5e499810a8a659d02b935f29c23&request_ts=1757890162&track_id=93470300 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.463 [4040.4040] INFO HttpDownloader: [0xaca15150]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=93470300 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.463 [4040.4040] INFO HttpDownloader: [0xac9245d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b37461f501754d13e8e607bbc98736cc&request_ts=1757890162&track_id=93470300 Sep 15 07:49:22 rivo-toshi qobuz-connect[4040]: 20250915 07:49:22.464 [4040.4040] INFO MediaEngine: [0xac8e4df8]: Waiting for current stream to start before starting audio renderer Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:22 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:22 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:22 rivo-toshi volumio[3408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 07:49:22 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:22 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.324 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=93470300&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1757893763&hmac=5aMM8VJtGzkZQUSvvQWgYNsIL68 Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.410 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: [Stream 3]: stream information have been fetched Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.690 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: [Stream 4]: Metadata became available: Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.690 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Title: ピアノ協奏曲 第27番 変ロ長調 K.595 - 第2楽章: Larghetto Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.690 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Artist: フリードリヒ・グルダ Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.690 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Album: モーツァルト：ピアノ協奏曲第25番・第27番 Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.690 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Album art URL: https://static.qobuz.com/images/covers/ja/7k/xoo8qef7p7kja_600.jpg Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.769 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=93470299&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1757893763&hmac=7H1PC34QsKavxGPaum80Bu4KpV0 Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.769 [4040.4040] INFO UrlAudioSource: [0xace28580]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=93470299&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1757893763&hmac=7H1PC34QsKavxGPaum80Bu4KpV0 Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.769 [4040.4040] INFO ContentFetcher: [0xacb56378]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=93470299&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1757893763&hmac=7H1PC34QsKavxGPaum80Bu4KpV0, offset: 0 Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.770 [4040.4040] INFO AudioRenderer: [0xac8e5018]: Starting audio renderer, initial playback state: Playing Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.770 [4040.4040] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: QobuzConnect: alread currrent service, send playback confirmation Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.774 [4040.4040] INFO SampleApp: Initiating playback, initial state: Playing Sep 15 07:49:23 rivo-toshi volumio[3408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.962 [4040.4040] INFO SampleApp: Playback state changed: Playing Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.962 [4040.4040] INFO StreamingEventReporter: [0xac8e5950]: Reporting streaming started event Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.963 [4040.4040] INFO ProtocolHandler: [0xac8e5538]: Need to send state update Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.963 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending StateUpdated message Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.963 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.963 [4040.4040] INFO QConnectMessageSender: [0xaca706f8]: Sending FileAudioQualityChanged message Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.964 [4040.4040] INFO QwspMessageSender: [0xaca706e8]: Sending Payload message Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.965 [4040.4040] INFO HttpDownloader: [0xac8e59a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: [Stream 4]: stream information have been fetched Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: [Stream 3]: Metadata became available: Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Title: ピアノ協奏曲 第27番 変ロ長調 K.595 - 第1楽章: Allegro (Cadenza: Mozart) Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Artist: フリードリヒ・グルダ Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Album: モーツァルト：ピアノ協奏曲第25番・第27番 Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO AudioStreamManager: [0xac8e4ee0]: Album art URL: https://static.qobuz.com/images/covers/ja/7k/xoo8qef7p7kja_600.jpg Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO SampleApp: [Stream 3]: Stream metadata became available: Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO SampleApp: Title: ピアノ協奏曲 第27番 変ロ長調 K.595 - 第1楽章: Allegro (Cadenza: Mozart) Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.967 [4040.4040] INFO SampleApp: Artist: フリードリヒ・グルダ Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.968 [4040.4040] INFO SampleApp: Album: モーツァルト：ピアノ協奏曲第25番・第27番 Sep 15 07:49:23 rivo-toshi qobuz-connect[4040]: 20250915 07:49:23.968 [4040.4040] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ja/7k/xoo8qef7p7kja_600.jpg Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:23 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:23 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:23 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:23 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:23 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:24 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:24 rivo-toshi volumio[3408]: info: CoreCommandRouter::servicePushState Sep 15 07:49:24 rivo-toshi volumio[3408]: info: CoreStateMachine::pushState Sep 15 07:49:24 rivo-toshi volumio[3408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 07:49:24 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioPushState Sep 15 07:49:24 rivo-toshi volumio[3408]: info: CoreCommandRouter::volumioGetState Sep 15 07:49:24 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output update for this device Sep 15 07:49:24 rivo-toshi volumio[3408]: info: MRS: Pushing multiroomSync output Sep 15 07:49:24 rivo-toshi volumio[3408]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Sep 15 07:49:24 rivo-toshi volumio[3408]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 07:49:24 rivo-toshi volumio[3408]: TypeError: Cannot read property 'indexOf' of undefined Sep 15 07:49:24 rivo-toshi volumio[3408]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Sep 15 07:49:24 rivo-toshi volumio[3408]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 15 07:49:24 rivo-toshi volumio[3408]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Sep 15 07:49:24 rivo-toshi volumio[3408]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.143 [4040.4040] INFO UrlAudioSource: [0xace28580]: Content info extracted Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.144 [4040.4040] INFO UrlAudioSource: [0xace28580]: Size: 570551317 Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.150 [4040.7626] INFO AudioDecoder: [0xace2b158]: Stream info: Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.157 [4040.7626] INFO AudioDecoder: [0xace2b158]: Total samples: 170086400 Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.157 [4040.7626] INFO AudioDecoder: [0xace2b158]: Sample rate: 192000 Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.158 [4040.7626] INFO AudioDecoder: [0xace2b158]: Channel count: 2 Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.158 [4040.7626] INFO AudioDecoder: [0xace2b158]: Bits per sample: 24 Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.158 [4040.7626] INFO AudioDecoder: [0xace2b158]: Duration: 885866 Sep 15 07:49:24 rivo-toshi qobuz-connect[4040]: 20250915 07:49:24.159 [4040.4040] INFO UrlAudioSource: [0xace28580]: Audio properties have changed Sep 15 07:49:24 rivo-toshi sudo[7635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 07:48 Sep 15 07:49:24 rivo-toshi sudo[7635]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:43:09 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="fc7d09bc07ffd188bff4808d8f79c746"