-- Logs begin at Fri 2023-10-20 23:06:54 CST, end at Wed 2026-02-11 17:03:10 CST. -- Feb 11 17:02:00 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:00 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:00 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:01 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:01 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:01 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:02 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:02 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:02 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:03 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:03 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:03 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:04 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:04 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:04 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:05 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:05 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:05 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:06 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:06 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:06 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:07 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:07 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:07 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:07 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:08 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:08 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:08 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:09 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:09 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:09 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:10 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:10 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:10 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:11 primo volumio[3124]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 11 17:02:11 primo volumio[3124]: info: Retrying Login Due to Network Error Feb 11 17:02:11 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:11 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:11 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:13 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:13 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:13 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:14 primo volumio5-onboarding[4040]: time=2026-02-11T17:02:14.108+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.100.200:56211 Feb 11 17:02:14 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:14 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:14 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:14 primo volumio[3124]: verbose: New Socket.io Connection to 192.168.100.233 from 192.168.100.200 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Feb 11 17:02:14 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:15 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:15 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 17:02:15 primo volumio[3124]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 11 17:02:15 primo volumio[3124]: info: Received Get System Info Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 17:02:15 primo volumio[3124]: info: Discovery: Getting this device information Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:15 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:15 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:15 primo volumio[3124]: info: Listing playlists Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::volumioGetQueue Feb 11 17:02:15 primo volumio[3124]: info: CoreStateMachine::getQueue Feb 11 17:02:15 primo volumio[3124]: info: CorePlayQueue::getQueue Feb 11 17:02:15 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:15 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:15 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 17:02:16 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:16 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:16 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:17 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:17 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:17 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:18 primo volumio[3124]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 11 17:02:18 primo volumio[3124]: info: Retrying Login Due to Network Error Feb 11 17:02:19 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:19 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:19 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:19 primo volumio5-onboarding[4040]: time=2026-02-11T17:02:19.606+08:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.100.200:56211 Feb 11 17:02:19 primo volumio5-onboarding[4040]: time=2026-02-11T17:02:19.606+08:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.100.200:56211 Feb 11 17:02:19 primo volumio5-onboarding[4040]: time=2026-02-11T17:02:19.612+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.100.200:56214 Feb 11 17:02:20 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:20 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:20 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:21 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:21 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:21 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:21 primo volumio[3124]: info: MyVolumio login type: Token Feb 11 17:02:21 primo volumio[3124]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 11 17:02:21 primo volumio[3124]: info: Retrying Login Due to Network Error Feb 11 17:02:22 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:22 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:22 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:23 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:23 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:23 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:25 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:25 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:25 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:26 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:26 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:26 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:27 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:27 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:27 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:27 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:27 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:27 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:28 primo volumio[3124]: info: MyVolumio login type: Token Feb 11 17:02:28 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:28 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:28 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:29 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:29 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:29 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:30 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:30 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:30 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:31 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:31 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:31 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:31 primo volumio[3124]: info: MyVolumio login type: Token Feb 11 17:02:33 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:33 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:33 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:33 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:33 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:33 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:35 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:35 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:35 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:36 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:36 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:36 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:37 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:37 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:37 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:38 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:38 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:38 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:39 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:39 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:39 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:40 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:40 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:40 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:41 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:41 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:41 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:43 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:43 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:43 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:44 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:44 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:44 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:45 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:45 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:45 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:46 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:46 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:46 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:48 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:48 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:48 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:49 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:49 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:49 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:50 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:50 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:50 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:51 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:51 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:51 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:51 primo volumio[3124]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 11 17:02:51 primo volumio[3124]: info: Retrying Login Due to Network Error Feb 11 17:02:52 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:52 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:52 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:52 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:52 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:52 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:53 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:53 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:53 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:54 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:54 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:54 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:55 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:55 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:55 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:57 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:57 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:57 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:58 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:58 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:58 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:58 primo volumio[3124]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 11 17:02:58 primo volumio[3124]: info: Retrying Login Due to Network Error Feb 11 17:02:59 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:59 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:59 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:02:59 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:02:59 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:02:59 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:00 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:03:00 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:00 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:01 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:03:01 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:01 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:01 primo volumio[3124]: info: MyVolumio login type: Token Feb 11 17:03:01 primo volumio[3124]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 11 17:03:01 primo volumio[3124]: info: Retrying Login Due to Network Error Feb 11 17:03:02 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:03:02 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:02 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:03 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:03:03 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:03 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:03 primo volumio[3124]: info: Preload queue cleared Feb 11 17:03:03 primo volumio[3124]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 17:03:03 primo volumio[3124]: info: CoreStateMachine::ClearQueue Feb 11 17:03:03 primo volumio[3124]: info: CoreStateMachine::stop Feb 11 17:03:03 primo volumio[3124]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 17:03:03 primo volumio[3124]: info: CorePlayQueue::clearPlayQueue Feb 11 17:03:03 primo volumio[3124]: info: CorePlayQueue::saveQueue Feb 11 17:03:03 primo volumio[3124]: info: CoreCommandRouter::volumioPushQueue Feb 11 17:03:03 primo volumio[3124]: info: CoreStateMachine::addQueueItems Feb 11 17:03:03 primo volumio[3124]: info: CorePlayQueue::addQueueItems Feb 11 17:03:03 primo volumio[3124]: info: Preload queue cleared Feb 11 17:03:03 primo volumio[3124]: info: Adding Item to queue: tidal://mymusic/albums/az/3974248 Feb 11 17:03:03 primo volumio[3124]: info: Exploding uri tidal://mymusic/albums/az/3974248 in service tidal Feb 11 17:03:04 primo volumio[3124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 17:03:05 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:05 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:05 primo volumio[3124]: info: MCU Signalled Playback Inactive Feb 11 17:03:05 primo volumio[3124]: info: explodeTIDALUri took 1558 milliseconds Feb 11 17:03:05 primo volumio[3124]: info: CoreCommandRouter::volumioPushQueue Feb 11 17:03:05 primo volumio[3124]: info: CorePlayQueue::saveQueue Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::updateTrackBlock Feb 11 17:03:05 primo volumio[3124]: info: CorePlayQueue::getTrackBlock Feb 11 17:03:05 primo volumio[3124]: info: CoreCommandRouter::volumioPlay Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::play index 0 Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::stop Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::play index undefined Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 17:03:05 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::startPlaybackTimer Feb 11 17:03:05 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:05 primo volumio[3124]: info: [1770800585535] ControllerTidal::clearAddPlayTrack Feb 11 17:03:05 primo volumio[3124]: info: Getting stream with soundQuality LOSSLESS Feb 11 17:03:05 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 17:03:05 primo volumio[3124]: info: sendMpdCommand stop took 15 milliseconds Feb 11 17:03:05 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 17:03:05 primo volumio[3124]: info: Feb 11 17:03:05 primo volumio[3124]: ---------------------------- MPD announces system playlist update Feb 11 17:03:05 primo volumio[3124]: info: Ignoring MPD Status Update Feb 11 17:03:05 primo volumio[3124]: info: sendMpdCommand clear took 5 milliseconds Feb 11 17:03:05 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3974249&soundQuality=LOSSLESS" Feb 11 17:03:05 primo volumio[3124]: info: Feb 11 17:03:05 primo volumio[3124]: ---------------------------- MPD announces system playlist update Feb 11 17:03:05 primo volumio[3124]: info: Ignoring MPD Status Update Feb 11 17:03:05 primo volumio[3124]: info: Feb 11 17:03:05 primo volumio[3124]: ---------------------------- MPD announces system playlist update Feb 11 17:03:05 primo volumio[3124]: info: Ignoring MPD Status Update Feb 11 17:03:05 primo volumio[3124]: error: updateQueue error: null Feb 11 17:03:05 primo volumio[3124]: info: Feb 11 17:03:05 primo volumio[3124]: ---------------------------- MPD announces system playlist update Feb 11 17:03:05 primo volumio[3124]: info: Ignoring MPD Status Update Feb 11 17:03:05 primo volumio[3124]: info: ------------------------------ 12ms Feb 11 17:03:05 primo volumio[3124]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3974249&soundQuality=LOSSLESS" took 10 milliseconds Feb 11 17:03:05 primo volumio[3124]: info: ------------------------------ 8ms Feb 11 17:03:05 primo volumio[3124]: info: ------------------------------ 6ms Feb 11 17:03:05 primo volumio[3124]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 17:03:05 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand play Feb 11 17:03:05 primo volumio[3124]: info: Feb 11 17:03:05 primo volumio[3124]: ---------------------------- MPD announces system playlist update Feb 11 17:03:05 primo volumio[3124]: info: Ignoring MPD Status Update Feb 11 17:03:05 primo volumio[3124]: info: Feb 11 17:03:05 primo volumio[3124]: ---------------------------- MPD announces system playlist update Feb 11 17:03:05 primo volumio[3124]: info: Ignoring MPD Status Update Feb 11 17:03:05 primo volumio[3124]: error: Upnp client error: Error: This socket has been ended by the other party Feb 11 17:03:05 primo volumio[3124]: info: ------------------------------ 12ms Feb 11 17:03:05 primo volumio[3124]: info: sendMpdCommand play took 9 milliseconds Feb 11 17:03:05 primo volumio[3124]: info: ------------------------------ 8ms Feb 11 17:03:05 primo volumio[3124]: info: ------------------------------ 6ms Feb 11 17:03:05 primo volumio[3124]: STREAMING PROXY: Handling url /?data=tidal://song/3974249&soundQuality=LOSSLESS Feb 11 17:03:05 primo volumio[3124]: info: Executing endpoint getStreamUrltidal Feb 11 17:03:05 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 11 17:03:06 primo volumio[3124]: info: getStreamUrl took 767 milliseconds Feb 11 17:03:06 primo volumio[3124]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2OGQxYTA0OGZmNjMzZGQ1MDIxYWUyODllNDY4MzA0ZV82MS5tcDQ/0.flac?token=1770804186~N2I0ZTE4NTJiZDc4OTA1MThlZjJmODY3ODVhY2NjMGI5YjVmZDk0NQ== Feb 11 17:03:06 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 11 17:03:06 primo volumio[3124]: STREAMING PROXY: Response: 200, length: 20772847 Feb 11 17:03:06 primo kernel: aml_tdm_open Feb 11 17:03:06 primo kernel: Not init audio effects Feb 11 17:03:06 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Feb 11 17:03:06 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Feb 11 17:03:06 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Feb 11 17:03:06 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Feb 11 17:03:06 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d111818, id(1), clksel(1) Feb 11 17:03:06 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Feb 11 17:03:06 primo kernel: dump_pcm_setting(ffffffc03d111818) Feb 11 17:03:06 primo kernel: pcm_mode(1) Feb 11 17:03:06 primo kernel: sysclk(11289600) Feb 11 17:03:06 primo kernel: sysclk_bclk_ratio(4) Feb 11 17:03:06 primo kernel: bclk(2822400) Feb 11 17:03:06 primo kernel: bclk_lrclk_ratio(64) Feb 11 17:03:06 primo kernel: lrclk(44100) Feb 11 17:03:06 primo kernel: tx_mask(0x3) Feb 11 17:03:06 primo kernel: rx_mask(0x3) Feb 11 17:03:06 primo kernel: slots(2) Feb 11 17:03:06 primo kernel: slot_width(32) Feb 11 17:03:06 primo kernel: lane_mask_in(0x2) Feb 11 17:03:06 primo kernel: lane_mask_out(0x1) Feb 11 17:03:06 primo kernel: lane_oe_mask_in(0x0) Feb 11 17:03:06 primo kernel: lane_oe_mask_out(0x0) Feb 11 17:03:06 primo kernel: lane_lb_mask_in(0x0) Feb 11 17:03:06 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Feb 11 17:03:06 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Feb 11 17:03:06 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Feb 11 17:03:06 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Feb 11 17:03:06 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Feb 11 17:03:06 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Feb 11 17:03:06 primo kernel: aml_tdm_prepare(), reset fddr Feb 11 17:03:06 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Feb 11 17:03:06 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Feb 11 17:03:06 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Feb 11 17:03:06 primo kernel: tdm playback mute: 0, lane_cnt = 8 Feb 11 17:03:06 primo kernel: aml_tdm_prepare(), reset fddr Feb 11 17:03:06 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Feb 11 17:03:06 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Feb 11 17:03:06 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Feb 11 17:03:06 primo kernel: tdm playback mute: 0, lane_cnt = 8 Feb 11 17:03:06 primo volumio[3124]: info: Feb 11 17:03:06 primo volumio[3124]: ---------------------------- MPD announces state update: player Feb 11 17:03:06 primo volumio[3124]: info: ControllerMpd::getState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand status Feb 11 17:03:06 primo volumio[3124]: info: Feb 11 17:03:06 primo volumio[3124]: ---------------------------- MPD announces state update: player Feb 11 17:03:06 primo volumio[3124]: info: ControllerMpd::getState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand status Feb 11 17:03:06 primo volumio[3124]: info: Feb 11 17:03:06 primo volumio[3124]: ---------------------------- MPD announces state update: player Feb 11 17:03:06 primo volumio[3124]: info: ControllerMpd::getState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand status Feb 11 17:03:06 primo volumio[3124]: info: Feb 11 17:03:06 primo volumio[3124]: ---------------------------- MPD announces state update: player Feb 11 17:03:06 primo volumio[3124]: info: sendMpdCommand status took 6 milliseconds Feb 11 17:03:06 primo volumio[3124]: info: ControllerMpd::getState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand status Feb 11 17:03:06 primo volumio[3124]: info: sendMpdCommand status took 6 milliseconds Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::parseState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::parseState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 17:03:06 primo volumio[3124]: info: Feb 11 17:03:06 primo volumio[3124]: ---------------------------- MPD announces state update: player Feb 11 17:03:06 primo volumio[3124]: info: ControllerMpd::getState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand status Feb 11 17:03:06 primo volumio[3124]: info: Feb 11 17:03:06 primo volumio[3124]: ---------------------------- MPD announces state update: player Feb 11 17:03:06 primo volumio[3124]: info: ControllerMpd::getState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand status Feb 11 17:03:06 primo volumio[3124]: info: sendMpdCommand status took 19 milliseconds Feb 11 17:03:06 primo volumio[3124]: info: sendMpdCommand status took 15 milliseconds Feb 11 17:03:06 primo volumio[3124]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 11 17:03:06 primo volumio[3124]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 11 17:03:06 primo volumio[3124]: info: sendMpdCommand status took 10 milliseconds Feb 11 17:03:06 primo volumio[3124]: info: sendMpdCommand status took 3 milliseconds Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::parseState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::parseState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::parseTrackInfo Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::parseTrackInfo Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::parseState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::parseState Feb 11 17:03:06 primo volumio[3124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 17:03:06 primo volumio[3124]: info: ControllerMpd::pushState Feb 11 17:03:06 primo volumio[3124]: info: CoreCommandRouter::servicePushState Feb 11 17:03:06 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:06 primo volumio[3124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3974249&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3974249&soundQuality=LOSSLESS","trackType":"tidal"} Feb 11 17:03:06 primo volumio[3124]: verbose: CURRENT POSITION 0 Feb 11 17:03:06 primo volumio[3124]: info: CoreStateMachine::syncState stateService play Feb 11 17:03:06 primo volumio[3124]: info: CoreStateMachine::syncState currentStatus stop Feb 11 17:03:06 primo volumio[3124]: info: ControllerMpd::pushState Feb 11 17:03:06 primo volumio[3124]: info: CoreCommandRouter::servicePushState Feb 11 17:03:06 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:06 primo volumio[3124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3974249&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3974249&soundQuality=LOSSLESS","trackType":"tidal"} Feb 11 17:03:06 primo volumio[3124]: verbose: CURRENT POSITION 0 Feb 11 17:03:06 primo volumio[3124]: info: CoreStateMachine::syncState stateService play Feb 11 17:03:06 primo volumio[3124]: info: CoreStateMachine::syncState currentStatus play Feb 11 17:03:06 primo volumio[3124]: info: Received an update from plugin. extracting info from payload Feb 11 17:03:06 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:06 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 17:03:06 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:06 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:06 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:06 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:06 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:06 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:06 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:06 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:06 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:06 primo volumio[3124]: info: ------------------------------ 54ms Feb 11 17:03:07 primo volumio[3124]: info: ------------------------------ 58ms Feb 11 17:03:07 primo volumio[3124]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 11 17:03:07 primo volumio[3124]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 11 17:03:07 primo volumio[3124]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 11 17:03:07 primo volumio[3124]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 11 17:03:07 primo volumio[3124]: verbose: ControllerMpd::parseTrackInfo Feb 11 17:03:07 primo volumio[3124]: verbose: ControllerMpd::parseTrackInfo Feb 11 17:03:07 primo volumio[3124]: verbose: ControllerMpd::parseTrackInfo Feb 11 17:03:07 primo volumio[3124]: verbose: ControllerMpd::parseTrackInfo Feb 11 17:03:07 primo volumio[3124]: info: ControllerMpd::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::servicePushState Feb 11 17:03:07 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:07 primo volumio[3124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3974249&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3974249&soundQuality=LOSSLESS","trackType":"tidal"} Feb 11 17:03:07 primo volumio[3124]: verbose: CURRENT POSITION 0 Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::syncState stateService play Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::syncState currentStatus play Feb 11 17:03:07 primo volumio[3124]: info: Received an update from plugin. extracting info from payload Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:07 primo volumio[3124]: info: ControllerMpd::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::servicePushState Feb 11 17:03:07 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:07 primo volumio[3124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3974249&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3974249&soundQuality=LOSSLESS","trackType":"tidal"} Feb 11 17:03:07 primo volumio[3124]: verbose: CURRENT POSITION 0 Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::syncState stateService play Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::syncState currentStatus play Feb 11 17:03:07 primo volumio[3124]: info: Received an update from plugin. extracting info from payload Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:07 primo volumio[3124]: info: ControllerMpd::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::servicePushState Feb 11 17:03:07 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:07 primo volumio[3124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3974249&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3974249&soundQuality=LOSSLESS","trackType":"tidal"} Feb 11 17:03:07 primo volumio[3124]: verbose: CURRENT POSITION 0 Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::syncState stateService play Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::syncState currentStatus play Feb 11 17:03:07 primo volumio[3124]: info: Received an update from plugin. extracting info from payload Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:07 primo volumio[3124]: info: ControllerMpd::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::servicePushState Feb 11 17:03:07 primo volumio[3124]: info: CorePlayQueue::getTrack 0 Feb 11 17:03:07 primo volumio[3124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3974249&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3974249&soundQuality=LOSSLESS","trackType":"tidal"} Feb 11 17:03:07 primo volumio[3124]: verbose: CURRENT POSITION 0 Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::syncState stateService play Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::syncState currentStatus play Feb 11 17:03:07 primo volumio[3124]: info: Received an update from plugin. extracting info from payload Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:07 primo volumio[3124]: info: CoreStateMachine::pushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioPushState Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::volumioGetState Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output update for this device Feb 11 17:03:07 primo volumio[3124]: info: MRS: Pushing multiroomSync output Feb 11 17:03:07 primo volumio[3124]: info: ------------------------------ 132ms Feb 11 17:03:07 primo volumio[3124]: info: ------------------------------ 131ms Feb 11 17:03:07 primo volumio[3124]: info: ------------------------------ 124ms Feb 11 17:03:07 primo volumio[3124]: info: ------------------------------ 118ms Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Updating RAAT Signal Path Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: Signalling Playback active due to playback status change Feb 11 17:03:07 primo volumio[3124]: info: MCU Signalled Playback Active Feb 11 17:03:07 primo kernel: asoc-aml-card auge_sound: tdm playback enable Feb 11 17:03:07 primo kernel: spdif_a is set to enable Feb 11 17:03:07 primo volumio[3124]: info: browseTIDALUri took 997 milliseconds Feb 11 17:03:07 primo volumio[3124]: info: Preload queue cleared Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974249 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974250 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974251 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974252 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974253 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974254 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974255 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974256 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974257 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974258 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974259 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974260 Feb 11 17:03:07 primo volumio[3124]: info: Preloading song: tidal://song/3974261 Feb 11 17:03:07 primo volumio[3124]: info: Exploding uri tidal://song/3974249 in service tidal Feb 11 17:03:07 primo volumio[3124]: info: Executing endpoint getSimilarAlbums Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 11 17:03:07 primo volumio[3124]: info: Exploding uri tidal://song/3974250 in service tidal Feb 11 17:03:07 primo volumio[3124]: info: Exploding uri tidal://song/3974251 in service tidal Feb 11 17:03:07 primo volumio[3124]: info: Executing endpoint metavolumio Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 17:03:07 primo volumio[3124]: info: Executing endpoint metavolumio Feb 11 17:03:07 primo volumio[3124]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 17:03:07 primo volumio[3124]: info: Exploding uri tidal://song/3974252 in service tidal Feb 11 17:03:07 primo volumio[3124]: info: Exploding uri tidal://song/3974253 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: Exploding uri tidal://song/3974254 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: Exploding uri tidal://song/3974255 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: Exploding uri tidal://song/3974256 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: Exploding uri tidal://song/3974257 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: Exploding uri tidal://song/3974258 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: Exploding uri tidal://song/3974259 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: Exploding uri tidal://song/3974260 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: Exploding uri tidal://song/3974261 in service tidal Feb 11 17:03:08 primo volumio[3124]: info: MyVolumio login type: Token Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 740 milliseconds Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 692 milliseconds Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 679 milliseconds Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 742 milliseconds Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 725 milliseconds Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 819 milliseconds Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 672 milliseconds Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 831 milliseconds Feb 11 17:03:08 primo volumio[3124]: info: explodeTIDALUri took 684 milliseconds Feb 11 17:03:09 primo volumio[3124]: info: explodeTIDALUri took 821 milliseconds Feb 11 17:03:09 primo volumio[3124]: info: explodeTIDALUri took 766 milliseconds Feb 11 17:03:09 primo volumio[3124]: info: explodeTIDALUri took 888 milliseconds Feb 11 17:03:09 primo volumio[3124]: info: explodeTIDALUri took 1566 milliseconds Feb 11 17:03:09 primo volumio[3124]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 17:03:09 primo volumio[3124]: Error: connect ECONNREFUSED 23.101.24.70:443 Feb 11 17:03:09 primo volumio[3124]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 11 17:03:09 primo volumio[3124]: errno: -111, Feb 11 17:03:09 primo volumio[3124]: code: 'ECONNREFUSED', Feb 11 17:03:09 primo volumio[3124]: syscall: 'connect', Feb 11 17:03:09 primo volumio[3124]: address: '23.101.24.70', Feb 11 17:03:09 primo volumio[3124]: port: 443 Feb 11 17:03:09 primo volumio[3124]: } Feb 11 17:03:09 primo volumio[3124]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 17:03:10 primo sudo[18103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 17:02 Feb 11 17:03:10 primo sudo[18103]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 07:52:21 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="060c5b31331b90c840ee52059a8e3db0"