-- Logs begin at Thu 2024-06-20 19:05:43 MDT, end at Sat 2024-07-13 18:31:25 MDT. -- Jul 13 18:30:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:14 volumio13 volumio[880]: error: [yt-cast-receiver] RPC connection error: Jul 13 18:30:14 volumio13 volumio[880]: (FetchError) request to https://www.youtube.com/api/lounge/bc/bind?AID=14272&CI=0&RID=rpc&SID=F896556B1BF2C3F4&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=ubgt8RphJL0OLlS9jcKnJiY_UGlN0R9X&id=83c6283b-caf3-40d5-98e9-0b00df89f46e&loungeIdToken=AGdO5p_ZUQfgJu_grjsEKSUsicF2BcE-L-4wUavMbjnEAzJu00qT66PZ6OZ0QxrWC7Hzp3omqyVg2pFrhX9oMIQhN4inhmZlCwm26atIhJDbk43fxtSkQtU&mdxVersion=2&name=YouTube%20on%20Volumio13&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=52ea39b06459 failed, reason: connect ECONNREFUSED 142.250.217.78:443 Jul 13 18:30:14 volumio13 volumio[880]: FetchError: request to https://www.youtube.com/api/lounge/bc/bind?AID=14272&CI=0&RID=rpc&SID=F896556B1BF2C3F4&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=ubgt8RphJL0OLlS9jcKnJiY_UGlN0R9X&id=83c6283b-caf3-40d5-98e9-0b00df89f46e&loungeIdToken=AGdO5p_ZUQfgJu_grjsEKSUsicF2BcE-L-4wUavMbjnEAzJu00qT66PZ6OZ0QxrWC7Hzp3omqyVg2pFrhX9oMIQhN4inhmZlCwm26atIhJDbk43fxtSkQtU&mdxVersion=2&name=YouTube%20on%20Volumio13&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=52ea39b06459 failed, reason: connect ECONNREFUSED 142.250.217.78:443 Jul 13 18:30:14 volumio13 volumio[880]: at ClientRequest. (/data/plugins/music_service/ytcr/node_modules/node-fetch/lib/index.js:1505:11) Jul 13 18:30:14 volumio13 volumio[880]: at ClientRequest.emit (events.js:315:20) Jul 13 18:30:14 volumio13 volumio[880]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Jul 13 18:30:14 volumio13 volumio[880]: at TLSSocket.emit (events.js:315:20) Jul 13 18:30:14 volumio13 volumio[880]: at emitErrorNT (internal/streams/destroy.js:106:8) Jul 13 18:30:14 volumio13 volumio[880]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Jul 13 18:30:14 volumio13 volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 13 18:30:14 volumio13 volumio[880]: error: [yt-cast-receiver] Retrying 1 / 3 Jul 13 18:30:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:25 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:25 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:25 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:25 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:25 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:25 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:26 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:26 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:26 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:26 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:26 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:26 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:27 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:27 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:27 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:27 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:27 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:27 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:27 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:28 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:28 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:28 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:28 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:28 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:28 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:28 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:29 volumio13 volumio[880]: info: VolumeController::SetAlsaVolume- Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:29 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:29 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 13 18:30:29 volumio13 volumio[880]: SPOTIFY: SPOTIFY VOLUME 65 Jul 13 18:30:29 volumio13 volumio[880]: SPOTIFY: VOLUMIO VOLUME 61 Jul 13 18:30:29 volumio13 volumio[880]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 13 18:30:29 volumio13 volumio[880]: info: Setting Spotify Volume from Volumio: 61 Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:29 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:30 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:30 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:30 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:30 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:30 volumio13 volumio[880]: SPOTIFY: SETTING SPOTIFY VOLUME 61 Jul 13 18:30:30 volumio13 volumio[880]: info: Sending Spotify command with payload to local API: /player/volume Jul 13 18:30:30 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:30 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:31 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:31 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:31 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:31 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:31 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:31 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:32 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:32 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:32 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:32 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:32 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:32 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:32 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:32 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:33 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:33 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:33 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:33 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:33 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:33 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:34 volumio13 volumio[880]: info: VolumeController::SetAlsaVolume- Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:34 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:34 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:34 volumio13 volumio[880]: SPOTIFY: SPOTIFY VOLUME 61 Jul 13 18:30:34 volumio13 volumio[880]: SPOTIFY: VOLUMIO VOLUME 57 Jul 13 18:30:34 volumio13 volumio[880]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 13 18:30:34 volumio13 volumio[880]: info: Setting Spotify Volume from Volumio: 57 Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:34 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:35 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:35 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:35 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:35 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:35 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:35 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:36 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:36 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:36 volumio13 volumio[880]: SPOTIFY: SETTING SPOTIFY VOLUME 57 Jul 13 18:30:36 volumio13 volumio[880]: info: Sending Spotify command with payload to local API: /player/volume Jul 13 18:30:36 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:36 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:36 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:36 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:36 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:36 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:37 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:37 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:37 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:37 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:37 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:37 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:38 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:38 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:38 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:38 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:38 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:38 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:39 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:39 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:39 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:39 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:39 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:39 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:40 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:40 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:40 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:40 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:40 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:40 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:40 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:40 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:41 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:41 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:41 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:41 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:41 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:41 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:42 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:42 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:42 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:42 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:42 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:42 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:43 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:43 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:43 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:43 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:43 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:43 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:44 volumio13 volumio[880]: info: Preload queue cleared Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::ClearQueue Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::stop Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::stPlaybackTimer Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::updateTrackBlock Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrackBlock Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::serviceStop Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::serviceStop Jul 13 18:30:44 volumio13 volumio[880]: info: [1720917044148] ControllerCalmRadio::stop Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::clearPlayQueue Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::saveQueue Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushQueue Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::addQueueItems Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::addQueueItems Jul 13 18:30:44 volumio13 volumio[880]: info: Preload queue cleared Jul 13 18:30:44 volumio13 volumio[880]: info: Adding Item to queue: calmradio://34/388 Jul 13 18:30:44 volumio13 volumio[880]: info: Exploding uri calmradio://34/388 in service calmradio Jul 13 18:30:44 volumio13 volumio[880]: info: Calm Radio explodeUri for Cat 34 Chan 388 Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushQueue Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::saveQueue Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::updateTrackBlock Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrackBlock Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioPlay Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::play index 0 Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::stop Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::play index undefined Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::startPlaybackTimer Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:44 volumio13 volumio[880]: info: [1720917044308] ControllerCalmRadio::clearAddPlayTrack Jul 13 18:30:44 volumio13 volumio[880]: { Jul 13 18:30:44 volumio13 volumio[880]: uri: 'calmradio://34/388', Jul 13 18:30:44 volumio13 volumio[880]: service: 'calmradio', Jul 13 18:30:44 volumio13 volumio[880]: trackType: 'CalmRadio', Jul 13 18:30:44 volumio13 volumio[880]: radioType: 'web', Jul 13 18:30:44 volumio13 volumio[880]: name: 'BAR ITALIA', Jul 13 18:30:44 volumio13 volumio[880]: title: 'BAR ITALIA', Jul 13 18:30:44 volumio13 volumio[880]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/388/21578a2962819e2eedb423d1215a84cbcc53960488c0f8007d727f224612.png', Jul 13 18:30:44 volumio13 volumio[880]: type: 'webradio', Jul 13 18:30:44 volumio13 volumio[880]: samplerate: '', Jul 13 18:30:44 volumio13 volumio[880]: bitdepth: 0, Jul 13 18:30:44 volumio13 volumio[880]: channels: 0 Jul 13 18:30:44 volumio13 volumio[880]: } Jul 13 18:30:44 volumio13 volumio[880]: info: Calm Radio getStreamUrl for Cat 34 Chan 388 Jul 13 18:30:44 volumio13 volumio[880]: STREAMURL: https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 18:30:44 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:44 volumio13 volumio[880]: info: Jul 13 18:30:44 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:44 volumio13 volumio[880]: info: sendMpdCommand stop took 323 milliseconds Jul 13 18:30:44 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:44 volumio13 volumio[880]: info: sendMpdCommand stop took 65 milliseconds Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 18:30:44 volumio13 volumio[880]: info: Jul 13 18:30:44 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:44 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:44 volumio13 volumio[880]: info: Jul 13 18:30:44 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:44 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:44 volumio13 volumio[880]: info: sendMpdCommand status took 11 milliseconds Jul 13 18:30:44 volumio13 volumio[880]: info: sendMpdCommand clear took 10 milliseconds Jul 13 18:30:44 volumio13 volumio[880]: info: sendMpdCommand status took 9 milliseconds Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc" Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:44 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:44 volumio13 volumio[880]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 13 18:30:44 volumio13 volumio[880]: info: ------------------------------ 56ms Jul 13 18:30:44 volumio13 volumio[880]: info: Jul 13 18:30:44 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:44 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:44 volumio13 volumio[880]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:44 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:44 volumio13 volumio[880]: error: updateQueue error: null Jul 13 18:30:44 volumio13 volumio[880]: info: Jul 13 18:30:44 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:44 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:44 volumio13 volumio[880]: info: ------------------------------ 114ms Jul 13 18:30:44 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 111 milliseconds Jul 13 18:30:44 volumio13 volumio[880]: info: sendMpdCommand addid "https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc" took 111 milliseconds Jul 13 18:30:44 volumio13 volumio[880]: info: ------------------------------ 66ms Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:44 volumio13 volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 13 18:30:44 volumio13 volumio[880]: verbose: MPD COMMAND [object Object] Jul 13 18:30:44 volumio13 volumio[880]: info: ------------------------------ 166ms Jul 13 18:30:44 volumio13 volumio[880]: info: Jul 13 18:30:44 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:44 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:44 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:44 volumio13 volumio[880]: info: Jul 13 18:30:44 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:44 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:44 volumio13 volumio[880]: info: ------------------------------ 61ms Jul 13 18:30:44 volumio13 volumio[880]: info: ------------------------------ 19ms Jul 13 18:30:44 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 18:30:44 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand play Jul 13 18:30:44 volumio13 volumio[880]: info: Jul 13 18:30:44 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:44 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:44 volumio13 volumio[880]: info: ------------------------------ 20ms Jul 13 18:30:44 volumio13 volumio[880]: info: sendMpdCommand play took 13 milliseconds Jul 13 18:30:44 volumio13 volumio[880]: info: ------------------------------ 12ms Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:44 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:45 volumio13 volumio[880]: info: camilladsp spawned new process with pid 7556, instance 1, run: true Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - Effects disabled Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - Effects disabled Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand status took 31 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand status took 30 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand status took 5 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand status took 4 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:45 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:45 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BAR ITALIA","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:45 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus stop Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:45 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:45 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BAR ITALIA","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:45 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:45 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 45ms Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 46ms Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:45 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:45 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BAR ITALIA","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:45 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:45 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:45 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:45 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BAR ITALIA","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:45 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:45 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 40ms Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 39ms Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 13 18:30:45 volumio13 volumio[880]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:45 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:45 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:45 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 8ms Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand status took 3 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 2ms Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand status took 1 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:45 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:45 volumio13 volumio[880]: info: Jul 13 18:30:45 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 4ms Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand status took 4 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 2ms Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:45 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:45 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1211,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Amedeo Minghi - Gomma americana","artist":"CALM RADIO - BAR ITALIA","album":null,"uri":"https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:45 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:45 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:45 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:45 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Amedeo Minghi - Gomma americana","artist":"CALM RADIO - BAR ITALIA","album":null,"uri":"https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:45 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:45 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 23ms Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 21ms Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand status took 17 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:45 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:45 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Amedeo Minghi - Gomma americana","artist":"CALM RADIO - BAR ITALIA","album":null,"uri":"https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:45 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:45 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 31ms Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 13 18:30:45 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:45 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:45 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:45 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1417,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Amedeo Minghi - Gomma americana","artist":"CALM RADIO - BAR ITALIA","album":null,"uri":"https://streams.calmradio.com/api/990/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:45 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:45 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:45 volumio13 volumio[880]: info: ------------------------------ 52ms Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:45 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:46 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:46 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:46 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:46 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:46 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:46 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:47 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:47 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:47 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:47 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:47 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:47 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:48 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:48 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:48 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:48 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:48 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:48 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:48 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:48 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:49 volumio13 volumio[880]: info: Preload queue cleared Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::ClearQueue Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::stop Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::stPlaybackTimer Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::updateTrackBlock Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrackBlock Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::serviceStop Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::serviceStop Jul 13 18:30:49 volumio13 volumio[880]: info: [1720917049340] ControllerCalmRadio::stop Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::clearPlayQueue Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::saveQueue Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushQueue Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::addQueueItems Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::addQueueItems Jul 13 18:30:49 volumio13 volumio[880]: info: Preload queue cleared Jul 13 18:30:49 volumio13 volumio[880]: info: Adding Item to queue: calmradio://34/309 Jul 13 18:30:49 volumio13 volumio[880]: info: Exploding uri calmradio://34/309 in service calmradio Jul 13 18:30:49 volumio13 volumio[880]: info: Calm Radio explodeUri for Cat 34 Chan 309 Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushQueue Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::saveQueue Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::updateTrackBlock Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrackBlock Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioPlay Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::play index 0 Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::stop Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::play index undefined Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::startPlaybackTimer Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:49 volumio13 volumio[880]: info: [1720917049472] ControllerCalmRadio::clearAddPlayTrack Jul 13 18:30:49 volumio13 volumio[880]: { Jul 13 18:30:49 volumio13 volumio[880]: uri: 'calmradio://34/309', Jul 13 18:30:49 volumio13 volumio[880]: service: 'calmradio', Jul 13 18:30:49 volumio13 volumio[880]: trackType: 'CalmRadio', Jul 13 18:30:49 volumio13 volumio[880]: radioType: 'web', Jul 13 18:30:49 volumio13 volumio[880]: name: 'CAFE PARIS', Jul 13 18:30:49 volumio13 volumio[880]: title: 'CAFE PARIS', Jul 13 18:30:49 volumio13 volumio[880]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/309/3ee678d0bd518c699adc49f0f3f6029f66e890b942a1baae78390fb8a419.png', Jul 13 18:30:49 volumio13 volumio[880]: type: 'webradio', Jul 13 18:30:49 volumio13 volumio[880]: samplerate: '', Jul 13 18:30:49 volumio13 volumio[880]: bitdepth: 0, Jul 13 18:30:49 volumio13 volumio[880]: channels: 0 Jul 13 18:30:49 volumio13 volumio[880]: } Jul 13 18:30:49 volumio13 volumio[880]: info: Calm Radio getStreamUrl for Cat 34 Chan 309 Jul 13 18:30:49 volumio13 volumio[880]: STREAMURL: https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 18:30:49 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:49 volumio13 volumio[880]: info: Jul 13 18:30:49 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:49 volumio13 volumio[880]: info: sendMpdCommand stop took 284 milliseconds Jul 13 18:30:49 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:49 volumio13 volumio[880]: info: sendMpdCommand stop took 42 milliseconds Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 18:30:49 volumio13 volumio[880]: info: Jul 13 18:30:49 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:49 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:49 volumio13 volumio[880]: info: Jul 13 18:30:49 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:49 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:49 volumio13 volumio[880]: info: sendMpdCommand status took 7 milliseconds Jul 13 18:30:49 volumio13 volumio[880]: info: sendMpdCommand clear took 7 milliseconds Jul 13 18:30:49 volumio13 volumio[880]: info: sendMpdCommand status took 5 milliseconds Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc" Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:49 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:49 volumio13 volumio[880]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 13 18:30:49 volumio13 volumio[880]: info: ------------------------------ 13ms Jul 13 18:30:49 volumio13 volumio[880]: info: Jul 13 18:30:49 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:49 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:49 volumio13 volumio[880]: error: updateQueue error: null Jul 13 18:30:49 volumio13 volumio[880]: info: Jul 13 18:30:49 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:49 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:49 volumio13 volumio[880]: info: ------------------------------ 19ms Jul 13 18:30:49 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 13 18:30:49 volumio13 volumio[880]: info: sendMpdCommand addid "https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc" took 19 milliseconds Jul 13 18:30:49 volumio13 volumio[880]: info: ------------------------------ 11ms Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:49 volumio13 volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 13 18:30:49 volumio13 volumio[880]: verbose: MPD COMMAND [object Object] Jul 13 18:30:49 volumio13 volumio[880]: info: ------------------------------ 30ms Jul 13 18:30:49 volumio13 volumio[880]: info: Jul 13 18:30:49 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:49 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:49 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:49 volumio13 volumio[880]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:49 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:49 volumio13 volumio[880]: info: Jul 13 18:30:49 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:49 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:49 volumio13 volumio[880]: info: ------------------------------ 22ms Jul 13 18:30:49 volumio13 volumio[880]: info: ------------------------------ 19ms Jul 13 18:30:49 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 18:30:49 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand play Jul 13 18:30:49 volumio13 volumio[880]: info: Jul 13 18:30:49 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:49 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:49 volumio13 volumio[880]: info: ------------------------------ 6ms Jul 13 18:30:49 volumio13 volumio[880]: info: sendMpdCommand play took 4 milliseconds Jul 13 18:30:49 volumio13 volumio[880]: info: ------------------------------ 4ms Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:49 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:50 volumio13 volumio[880]: info: camilladsp spawned new process with pid 7581, instance 1, run: true Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:50 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 13 18:30:50 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 13 18:30:50 volumio13 volumio[880]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 13 18:30:50 volumio13 volumio[880]: info: FusionDsp - Effects disabled Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand status took 8 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand status took 7 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand status took 3 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand status took 1 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:50 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:50 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CAFE PARIS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:50 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus stop Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:50 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:50 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CAFE PARIS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:50 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:50 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 21ms Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 34ms Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:50 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:50 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CAFE PARIS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:50 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:50 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:50 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:50 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CAFE PARIS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:50 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:50 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 61ms Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 59ms Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:50 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:50 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:50 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 8ms Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand status took 5 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 4ms Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand status took 3 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:50 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:50 volumio13 volumio[880]: info: Jul 13 18:30:50 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 3ms Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand status took 3 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:50 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:50 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Georges Ulmer - Pigalle (Curtain Time)","artist":"CALM RADIO - CAFE PARIS","album":null,"uri":"https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:50 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:50 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:50 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:50 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1291,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Georges Ulmer - Pigalle (Curtain Time)","artist":"CALM RADIO - CAFE PARIS","album":null,"uri":"https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:50 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:50 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 29ms Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 25ms Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 22ms Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand status took 21 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:50 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:50 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Georges Ulmer - Pigalle (Curtain Time)","artist":"CALM RADIO - CAFE PARIS","album":null,"uri":"https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:50 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:50 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 38ms Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 55 milliseconds Jul 13 18:30:50 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:50 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:50 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:50 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Georges Ulmer - Pigalle (Curtain Time)","artist":"CALM RADIO - CAFE PARIS","album":null,"uri":"https://streams.calmradio.com/api/317/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:50 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:50 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:50 volumio13 volumio[880]: info: ------------------------------ 88ms Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:50 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:51 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:51 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:51 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:51 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:51 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:51 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:52 volumio13 volumio[880]: info: Preload queue cleared Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::ClearQueue Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::stop Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::stPlaybackTimer Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::updateTrackBlock Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrackBlock Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::serviceStop Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::serviceStop Jul 13 18:30:52 volumio13 volumio[880]: info: [1720917052326] ControllerCalmRadio::stop Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::clearPlayQueue Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::saveQueue Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushQueue Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::addQueueItems Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::addQueueItems Jul 13 18:30:52 volumio13 volumio[880]: info: Preload queue cleared Jul 13 18:30:52 volumio13 volumio[880]: info: Adding Item to queue: calmradio://34/566 Jul 13 18:30:52 volumio13 volumio[880]: info: Exploding uri calmradio://34/566 in service calmradio Jul 13 18:30:52 volumio13 volumio[880]: info: Calm Radio explodeUri for Cat 34 Chan 566 Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushQueue Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::saveQueue Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::updateTrackBlock Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrackBlock Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioPlay Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::play index 0 Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::stop Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::play index undefined Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::startPlaybackTimer Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:52 volumio13 volumio[880]: info: [1720917052433] ControllerCalmRadio::clearAddPlayTrack Jul 13 18:30:52 volumio13 volumio[880]: { Jul 13 18:30:52 volumio13 volumio[880]: uri: 'calmradio://34/566', Jul 13 18:30:52 volumio13 volumio[880]: service: 'calmradio', Jul 13 18:30:52 volumio13 volumio[880]: trackType: 'CalmRadio', Jul 13 18:30:52 volumio13 volumio[880]: radioType: 'web', Jul 13 18:30:52 volumio13 volumio[880]: name: 'CELTIC HARP', Jul 13 18:30:52 volumio13 volumio[880]: title: 'CELTIC HARP', Jul 13 18:30:52 volumio13 volumio[880]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/566/d04860cb340f1ab7455626bd5d1e6c847ec82954324a5b8dfd79a2aa42a3.png', Jul 13 18:30:52 volumio13 volumio[880]: type: 'webradio', Jul 13 18:30:52 volumio13 volumio[880]: samplerate: '', Jul 13 18:30:52 volumio13 volumio[880]: bitdepth: 0, Jul 13 18:30:52 volumio13 volumio[880]: channels: 0 Jul 13 18:30:52 volumio13 volumio[880]: } Jul 13 18:30:52 volumio13 volumio[880]: info: Calm Radio getStreamUrl for Cat 34 Chan 566 Jul 13 18:30:52 volumio13 volumio[880]: STREAMURL: https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 18:30:52 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:52 volumio13 volumio[880]: info: Jul 13 18:30:52 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:52 volumio13 volumio[880]: info: sendMpdCommand stop took 251 milliseconds Jul 13 18:30:52 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:52 volumio13 volumio[880]: info: sendMpdCommand stop took 34 milliseconds Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 18:30:52 volumio13 volumio[880]: info: Jul 13 18:30:52 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:52 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:52 volumio13 volumio[880]: info: Jul 13 18:30:52 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:52 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:52 volumio13 volumio[880]: info: sendMpdCommand status took 7 milliseconds Jul 13 18:30:52 volumio13 volumio[880]: info: sendMpdCommand clear took 6 milliseconds Jul 13 18:30:52 volumio13 volumio[880]: info: sendMpdCommand status took 5 milliseconds Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc" Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:52 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:52 volumio13 volumio[880]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 13 18:30:52 volumio13 volumio[880]: info: ------------------------------ 12ms Jul 13 18:30:52 volumio13 volumio[880]: info: Jul 13 18:30:52 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:52 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:52 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:52 volumio13 volumio[880]: error: updateQueue error: null Jul 13 18:30:52 volumio13 volumio[880]: info: Jul 13 18:30:52 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:52 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:52 volumio13 volumio[880]: info: ------------------------------ 14ms Jul 13 18:30:52 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 13 18:30:52 volumio13 volumio[880]: info: sendMpdCommand addid "https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc" took 14 milliseconds Jul 13 18:30:52 volumio13 volumio[880]: info: ------------------------------ 7ms Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:52 volumio13 volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 13 18:30:52 volumio13 volumio[880]: verbose: MPD COMMAND [object Object] Jul 13 18:30:52 volumio13 volumio[880]: info: ------------------------------ 24ms Jul 13 18:30:52 volumio13 volumio[880]: info: Jul 13 18:30:52 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:52 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:52 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:52 volumio13 volumio[880]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 13 18:30:52 volumio13 volumio[880]: info: Jul 13 18:30:52 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:52 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:52 volumio13 volumio[880]: info: ------------------------------ 10ms Jul 13 18:30:52 volumio13 volumio[880]: info: ------------------------------ 6ms Jul 13 18:30:52 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 18:30:52 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand play Jul 13 18:30:52 volumio13 volumio[880]: info: Jul 13 18:30:52 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:52 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:52 volumio13 volumio[880]: info: ------------------------------ 4ms Jul 13 18:30:52 volumio13 volumio[880]: info: sendMpdCommand play took 11 milliseconds Jul 13 18:30:52 volumio13 volumio[880]: info: ------------------------------ 11ms Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:52 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:53 volumio13 volumio[880]: info: camilladsp spawned new process with pid 7601, instance 1, run: true Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - Effects disabled Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - Effects disabled Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand status took 19 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand status took 19 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand status took 8 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand status took 7 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:53 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:53 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:53 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus stop Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:53 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:53 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:53 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:53 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 35ms Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 37ms Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:53 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:53 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:53 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:53 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:53 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:53 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:53 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:53 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 38ms Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 36ms Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 13 18:30:53 volumio13 volumio[880]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:53 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:53 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:53 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 8ms Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand status took 4 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 4ms Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand status took 2 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:53 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:53 volumio13 volumio[880]: info: Jul 13 18:30:53 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 5ms Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand status took 4 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:53 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:53 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1268,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Celia Briar - Carrickfergus","artist":"CELTIC HARP","album":null,"uri":"https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:53 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:53 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:53 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:53 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Celia Briar - Carrickfergus","artist":"CELTIC HARP","album":null,"uri":"https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:53 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:53 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 22ms Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 21ms Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 18ms Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand status took 18 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:53 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:53 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1577,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Celia Briar - Carrickfergus","artist":"CELTIC HARP","album":null,"uri":"https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:53 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:53 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 36ms Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 53 milliseconds Jul 13 18:30:53 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:53 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:53 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:53 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Celia Briar - Carrickfergus","artist":"CELTIC HARP","album":null,"uri":"https://streams.calmradio.com/api/1890/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:53 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:53 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:53 volumio13 volumio[880]: info: ------------------------------ 84ms Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:53 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:54 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:54 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:54 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:54 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:54 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:54 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:55 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:55 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:55 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:55 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:55 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:55 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:56 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:56 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:56 volumio13 ntpd[828]: 206.108.0.133 local addr 192.168.5.194 -> Jul 13 18:30:56 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:56 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:56 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:56 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:57 volumio13 volumio[880]: info: Preload queue cleared Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::ClearQueue Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::stop Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::stPlaybackTimer Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::updateTrackBlock Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrackBlock Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::serviceStop Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::serviceStop Jul 13 18:30:57 volumio13 volumio[880]: info: [1720917057508] ControllerCalmRadio::stop Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::clearPlayQueue Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::saveQueue Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushQueue Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::addQueueItems Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::addQueueItems Jul 13 18:30:57 volumio13 volumio[880]: info: Preload queue cleared Jul 13 18:30:57 volumio13 volumio[880]: info: Adding Item to queue: calmradio://34/67 Jul 13 18:30:57 volumio13 volumio[880]: info: Exploding uri calmradio://34/67 in service calmradio Jul 13 18:30:57 volumio13 volumio[880]: info: Calm Radio explodeUri for Cat 34 Chan 67 Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushQueue Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::saveQueue Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::updateTrackBlock Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrackBlock Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioPlay Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::play index 0 Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::stop Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::play index undefined Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::startPlaybackTimer Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:57 volumio13 volumio[880]: info: [1720917057620] ControllerCalmRadio::clearAddPlayTrack Jul 13 18:30:57 volumio13 volumio[880]: { Jul 13 18:30:57 volumio13 volumio[880]: uri: 'calmradio://34/67', Jul 13 18:30:57 volumio13 volumio[880]: service: 'calmradio', Jul 13 18:30:57 volumio13 volumio[880]: trackType: 'CalmRadio', Jul 13 18:30:57 volumio13 volumio[880]: radioType: 'web', Jul 13 18:30:57 volumio13 volumio[880]: name: 'NATIVE FLUTE', Jul 13 18:30:57 volumio13 volumio[880]: title: 'NATIVE FLUTE', Jul 13 18:30:57 volumio13 volumio[880]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/067/0c194d9e28a270845fd33ddab9ee3ae18162bfd4a4cc323c9e25a3e56503.png', Jul 13 18:30:57 volumio13 volumio[880]: type: 'webradio', Jul 13 18:30:57 volumio13 volumio[880]: samplerate: '', Jul 13 18:30:57 volumio13 volumio[880]: bitdepth: 0, Jul 13 18:30:57 volumio13 volumio[880]: channels: 0 Jul 13 18:30:57 volumio13 volumio[880]: } Jul 13 18:30:57 volumio13 volumio[880]: info: Calm Radio getStreamUrl for Cat 34 Chan 67 Jul 13 18:30:57 volumio13 volumio[880]: STREAMURL: https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 18:30:57 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:57 volumio13 volumio[880]: info: Jul 13 18:30:57 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:57 volumio13 volumio[880]: info: sendMpdCommand stop took 281 milliseconds Jul 13 18:30:57 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:57 volumio13 volumio[880]: info: sendMpdCommand stop took 44 milliseconds Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 18:30:57 volumio13 volumio[880]: info: Jul 13 18:30:57 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:57 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:57 volumio13 volumio[880]: info: Jul 13 18:30:57 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:57 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:57 volumio13 volumio[880]: info: sendMpdCommand status took 7 milliseconds Jul 13 18:30:57 volumio13 volumio[880]: info: sendMpdCommand clear took 6 milliseconds Jul 13 18:30:57 volumio13 volumio[880]: info: sendMpdCommand status took 4 milliseconds Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc" Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:57 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:57 volumio13 volumio[880]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 13 18:30:57 volumio13 volumio[880]: info: ------------------------------ 16ms Jul 13 18:30:57 volumio13 volumio[880]: info: Jul 13 18:30:57 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:57 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:57 volumio13 volumio[880]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:57 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:57 volumio13 volumio[880]: error: updateQueue error: null Jul 13 18:30:57 volumio13 volumio[880]: info: Jul 13 18:30:57 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:57 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:57 volumio13 volumio[880]: info: ------------------------------ 42ms Jul 13 18:30:57 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 40 milliseconds Jul 13 18:30:57 volumio13 volumio[880]: info: sendMpdCommand addid "https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc" took 40 milliseconds Jul 13 18:30:57 volumio13 volumio[880]: info: ------------------------------ 31ms Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:57 volumio13 volumio[880]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 13 18:30:57 volumio13 volumio[880]: verbose: MPD COMMAND [object Object] Jul 13 18:30:57 volumio13 volumio[880]: info: ------------------------------ 55ms Jul 13 18:30:57 volumio13 volumio[880]: info: Jul 13 18:30:57 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:57 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:57 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:57 volumio13 volumio[880]: info: Jul 13 18:30:57 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:57 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:57 volumio13 volumio[880]: info: ------------------------------ 16ms Jul 13 18:30:57 volumio13 volumio[880]: info: ------------------------------ 9ms Jul 13 18:30:57 volumio13 volumio[880]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 18:30:57 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand play Jul 13 18:30:57 volumio13 volumio[880]: info: Jul 13 18:30:57 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:57 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:57 volumio13 volumio[880]: info: ------------------------------ 8ms Jul 13 18:30:57 volumio13 volumio[880]: info: sendMpdCommand play took 5 milliseconds Jul 13 18:30:57 volumio13 volumio[880]: info: ------------------------------ 5ms Jul 13 18:30:57 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:58 volumio13 volumio[880]: info: camilladsp spawned new process with pid 7628, instance 1, run: true Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:58 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 13 18:30:58 volumio13 volumio[880]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 13 18:30:58 volumio13 volumio[880]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 13 18:30:58 volumio13 volumio[880]: info: FusionDsp - Effects disabled Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand status took 11 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand status took 10 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand status took 3 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand status took 1 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:58 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:58 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NATIVE FLUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:58 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus stop Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:58 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:58 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NATIVE FLUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:58 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:58 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 21ms Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 25ms Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:58 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:58 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NATIVE FLUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:58 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:58 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:58 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:58 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NATIVE FLUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:58 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:58 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 46ms Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 46ms Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:58 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:58 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:58 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 9ms Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand status took 7 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces system playlist update Jul 13 18:30:58 volumio13 volumio[880]: info: Ignoring MPD Status Update Jul 13 18:30:58 volumio13 volumio[880]: info: Jul 13 18:30:58 volumio13 volumio[880]: ---------------------------- MPD announces state update: player Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::getState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand status Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 8ms Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand status took 7 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 8ms Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand status took 4 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 3ms Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand status took 3 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseState Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:58 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:58 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1097,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Coyote Oldman - Animal Songs","artist":"NATIVE FLUTE","album":null,"uri":"https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:58 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:58 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 20ms Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:58 volumio13 volumio[880]: verbose: ControllerMpd::parseTrackInfo Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:58 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:58 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Coyote Oldman - Animal Songs","artist":"NATIVE FLUTE","album":null,"uri":"https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:58 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:58 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:58 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:58 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1314,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Coyote Oldman - Animal Songs","artist":"NATIVE FLUTE","album":null,"uri":"https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:58 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:58 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: ControllerMpd::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::servicePushState Jul 13 18:30:58 volumio13 volumio[880]: info: CorePlayQueue::getTrack 0 Jul 13 18:30:58 volumio13 volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Coyote Oldman - Animal Songs","artist":"NATIVE FLUTE","album":null,"uri":"https://streams.calmradio.com/api/77/320/stream?user=andrey.golovchenko%40gmail.com&pass=2fc56d421d195af67bc1eb5c9b1df5d2a83f5019be4bfc62256ecdc3f3393ef3fa877eae8e9b1f09ea743a4d2d1ed7fc","trackType":""} Jul 13 18:30:58 volumio13 volumio[880]: verbose: CURRENT POSITION 0 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState stateService play Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::syncState currentStatus play Jul 13 18:30:58 volumio13 volumio[880]: info: Received an update from plugin. extracting info from payload Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreStateMachine::pushState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioPushState Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 58ms Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 55ms Jul 13 18:30:58 volumio13 volumio[880]: info: ------------------------------ 54ms Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:58 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:59 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:59 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:59 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:59 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:59 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:30:59 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:00 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:01 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:02 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:03 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:04 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:05 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:06 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:07 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:08 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:09 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:10 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:11 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:12 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:13 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:14 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:15 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:16 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:17 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:18 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:19 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:20 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:21 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:22 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:23 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:24 volumio13 volumio[880]: info: CoreCommandRouter::volumioGetState Jul 13 18:31:24 volumio13 volumio[880]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jul 13 18:31:24 volumio13 volumio[880]: info: PLUGIN onReboot : networkfs Jul 13 18:31:24 volumio13 volumio[880]: info: PLUGIN onReboot : audiophonicsonoff Jul 13 18:31:24 volumio13 volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 18:31:24 volumio13 volumio[880]: TypeError: Cannot read property 'writeSync' of undefined Jul 13 18:31:24 volumio13 volumio[880]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jul 13 18:31:24 volumio13 volumio[880]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jul 13 18:31:24 volumio13 volumio[880]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jul 13 18:31:24 volumio13 volumio[880]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 13 18:31:24 volumio13 volumio[880]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 13 18:31:24 volumio13 volumio[880]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jul 13 18:31:24 volumio13 volumio[880]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Jul 13 18:31:24 volumio13 volumio[880]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:857:33) Jul 13 18:31:24 volumio13 volumio[880]: at Socket.emit (events.js:315:20) Jul 13 18:31:24 volumio13 volumio[880]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 13 18:31:24 volumio13 volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 13 18:31:24 volumio13 volumio[880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 18:31:25 volumio13 sudo[7741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 18:30 Jul 13 18:31:25 volumio13 sudo[7741]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"