-- Logs begin at Mon 2024-07-15 12:32:19 UTC, end at Mon 2024-07-15 12:34:55 UTC. -- Jul 15 12:33:00 volumio volumio[1052]: info: Setting Geolocation for MyVolumio to eu7 Jul 15 12:33:00 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:00 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:00 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:00 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:00 volumio volumio[1052]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 15 12:33:01 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:01 volumio volumio[1052]: info: Updating MyVolumio device info Jul 15 12:33:01 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:01 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:01 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:01 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:01 volumio volumio[1052]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 15 12:33:04 volumio volumio[1052]: info: MYVOLUMIO: Adding device Jul 15 12:33:04 volumio volumio[1052]: info: MYVOLUMIO: Evaluating Server Jul 15 12:33:05 volumio volumio[1052]: info: Setting Geolocation for MyVolumio to eu7 Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 15 12:33:05 volumio volumio[1052]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 15 12:33:05 volumio volumio[1052]: info: Received Get System Version Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 12:33:05 volumio volumio[1052]: info: Received Get System Info Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 12:33:05 volumio volumio[1052]: info: Discovery: Getting this device information Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Jul 15 12:33:05 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Jul 15 12:33:05 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 12:33:06 volumio volumio[1052]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 15 12:33:06 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:06 volumio volumio[1052]: info: Updating MyVolumio device info Jul 15 12:33:06 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:06 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:06 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:06 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:07 volumio volumio[1052]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 15 12:33:10 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 15 12:33:10 volumio volumio[1052]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 15 12:33:10 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Jul 15 12:33:10 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Jul 15 12:33:11 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:14 volumio volumio[1052]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 15 12:33:14 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:14 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 12:33:14 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:14 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 15 12:33:15 volumio volumio-remote-updater[754]: No test mode Jul 15 12:33:15 volumio volumio-remote-updater[754]: No alpha test mode Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 15 12:33:15 volumio volumio[1052]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::volumioGetBrowseSources Jul 15 12:33:15 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 12:33:16 volumio volumio[1052]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 15 12:33:16 volumio volumio[1052]: info: Completed starting MyVolumio Plugin Jul 15 12:33:16 volumio volumio[1052]: info: BOOT COMPLETED Jul 15 12:33:16 volumio volumio[1052]: [Metrics] CommandRouter: 39s 727.60ms Jul 15 12:33:16 volumio volumio[1052]: info: CoreCommandRouter::volumiosetStartupVolume Jul 15 12:33:16 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:33:16 volumio volumio[1052]: info: VolumeController:: Setting startup Volume 25 Jul 15 12:33:16 volumio volumio[1052]: info: VolumeController::SetAlsaVolume25 Jul 15 12:33:16 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:33:16 volumio volumio[1052]: info: CoreCommandRouter::Close All Modals sent Jul 15 12:33:16 volumio volumio[1052]: info: CoreCommandRouter::Close All Modals sent Jul 15 12:33:16 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:33:16 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Jul 15 12:33:16 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:33:16 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:33:16 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:17 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 15 12:33:17 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 15 12:33:17 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 15 12:33:21 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:26 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:31 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:36 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:42 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:47 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:52 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:33:57 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:02 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:07 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:12 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:17 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:23 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:26 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:26 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:28 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:32 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:32 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:33 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:34 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:34 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:36 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:36 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@e39104a9d66b874e7c4af469e352240c Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@edfbb281bf6d5ba6f59d905312b34cec Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@a1eaaa7a056e08702ea100e6bcd2b3d1 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@6a9edfd1252d93afc3041c0654f95bd9 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@9e40c46269be1567f30011ca734d824f Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@619ea48bfbcfa9909e8ceb54fc227c4d Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@2d6eddb79b0d137919b3e852e5ad9011 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@268e7b8861fbd11fbcf7969399edd00b Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@45be90260b62a46b2e39125ff011dc69 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@32769d097725abb02ec5af20a5c29b22 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1d19594a8009ced2eaf4c53fb2e5da70 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@d268c7dec5f0533ad64520688bd77a0f Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@9fb49980746bc6d536087521fc25a9b1 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1f4ecf91f594c3086ed8c06caf641046 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@af199dbbe054e02a3338b280966861c7 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@d721049dd3cd065671a2c7d364834743 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1349548d65e3bb090772e48c024922cd Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@2bc430c7661fbda97a7e6bf900d11cdb Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@db145f1b8f01a135796e4bd30d82af27 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@601bc7f3949882e6b14058625e08d141 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@41c5f49be5850b15afc933588548f1df Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@78768c803b93fcd909444b2c28666b49 Jul 15 12:34:36 volumio volumio[1052]: info: Preloading song: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@0977bc6a25a25d4cf21cd89c00c7caa5 Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@e39104a9d66b874e7c4af469e352240c in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@edfbb281bf6d5ba6f59d905312b34cec in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@a1eaaa7a056e08702ea100e6bcd2b3d1 in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@6a9edfd1252d93afc3041c0654f95bd9 in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@9e40c46269be1567f30011ca734d824f in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@619ea48bfbcfa9909e8ceb54fc227c4d in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@2d6eddb79b0d137919b3e852e5ad9011 in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@268e7b8861fbd11fbcf7969399edd00b in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@45be90260b62a46b2e39125ff011dc69 in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@32769d097725abb02ec5af20a5c29b22 in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1d19594a8009ced2eaf4c53fb2e5da70 in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@d268c7dec5f0533ad64520688bd77a0f in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@9fb49980746bc6d536087521fc25a9b1 in service upnp_browser Jul 15 12:34:36 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1f4ecf91f594c3086ed8c06caf641046 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@af199dbbe054e02a3338b280966861c7 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@d721049dd3cd065671a2c7d364834743 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1349548d65e3bb090772e48c024922cd in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@2bc430c7661fbda97a7e6bf900d11cdb in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@db145f1b8f01a135796e4bd30d82af27 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@601bc7f3949882e6b14058625e08d141 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@41c5f49be5850b15afc933588548f1df in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@78768c803b93fcd909444b2c28666b49 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@0977bc6a25a25d4cf21cd89c00c7caa5 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:37 volumio volumio[1052]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 15 12:34:37 volumio volumio[1052]: info: CoreStateMachine::ClearQueue Jul 15 12:34:37 volumio volumio[1052]: info: CoreStateMachine::stop Jul 15 12:34:37 volumio volumio[1052]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:34:37 volumio volumio[1052]: info: CorePlayQueue::clearPlayQueue Jul 15 12:34:37 volumio volumio[1052]: info: CorePlayQueue::saveQueue Jul 15 12:34:37 volumio volumio[1052]: info: CoreCommandRouter::volumioPushQueue Jul 15 12:34:37 volumio volumio[1052]: info: CoreStateMachine::addQueueItems Jul 15 12:34:37 volumio volumio[1052]: info: CorePlayQueue::addQueueItems Jul 15 12:34:37 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@ST-b09001fd0306ea0a596a1d56fc155480 Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@ST-b09001fd0306ea0a596a1d56fc155480 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@SF-66e69fabfe1cc0d17ee805f50a82533e Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@SF-66e69fabfe1cc0d17ee805f50a82533e in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@TF-83cdf92a9caa791cd12ceb80d1def693 Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@TF-83cdf92a9caa791cd12ceb80d1def693 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@SF-5dd8e4c180c3a12190632839b2b36d9f Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@SF-5dd8e4c180c3a12190632839b2b36d9f in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@UF-7e9941ae4e2756af9991694eae969d62 Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/folder/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@UF-7e9941ae4e2756af9991694eae969d62 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@e39104a9d66b874e7c4af469e352240c Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@e39104a9d66b874e7c4af469e352240c Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@edfbb281bf6d5ba6f59d905312b34cec Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@edfbb281bf6d5ba6f59d905312b34cec Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@a1eaaa7a056e08702ea100e6bcd2b3d1 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@a1eaaa7a056e08702ea100e6bcd2b3d1 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@6a9edfd1252d93afc3041c0654f95bd9 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@6a9edfd1252d93afc3041c0654f95bd9 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@9e40c46269be1567f30011ca734d824f Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@9e40c46269be1567f30011ca734d824f Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@619ea48bfbcfa9909e8ceb54fc227c4d Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@619ea48bfbcfa9909e8ceb54fc227c4d Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@2d6eddb79b0d137919b3e852e5ad9011 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@2d6eddb79b0d137919b3e852e5ad9011 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@268e7b8861fbd11fbcf7969399edd00b Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@268e7b8861fbd11fbcf7969399edd00b Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@45be90260b62a46b2e39125ff011dc69 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@45be90260b62a46b2e39125ff011dc69 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@32769d097725abb02ec5af20a5c29b22 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@32769d097725abb02ec5af20a5c29b22 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1d19594a8009ced2eaf4c53fb2e5da70 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1d19594a8009ced2eaf4c53fb2e5da70 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@d268c7dec5f0533ad64520688bd77a0f Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@d268c7dec5f0533ad64520688bd77a0f Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@9fb49980746bc6d536087521fc25a9b1 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@9fb49980746bc6d536087521fc25a9b1 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1f4ecf91f594c3086ed8c06caf641046 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1f4ecf91f594c3086ed8c06caf641046 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@af199dbbe054e02a3338b280966861c7 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@af199dbbe054e02a3338b280966861c7 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@d721049dd3cd065671a2c7d364834743 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@d721049dd3cd065671a2c7d364834743 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1349548d65e3bb090772e48c024922cd Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@1349548d65e3bb090772e48c024922cd Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@2bc430c7661fbda97a7e6bf900d11cdb Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@2bc430c7661fbda97a7e6bf900d11cdb Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@db145f1b8f01a135796e4bd30d82af27 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@db145f1b8f01a135796e4bd30d82af27 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@601bc7f3949882e6b14058625e08d141 Jul 15 12:34:37 volumio volumio[1052]: info: Using cached record of: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@601bc7f3949882e6b14058625e08d141 Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@41c5f49be5850b15afc933588548f1df Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@41c5f49be5850b15afc933588548f1df in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@78768c803b93fcd909444b2c28666b49 Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@78768c803b93fcd909444b2c28666b49 in service upnp_browser Jul 15 12:34:37 volumio volumio[1052]: info: Adding Item to queue: upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@0977bc6a25a25d4cf21cd89c00c7caa5 Jul 15 12:34:37 volumio volumio[1052]: info: Exploding uri upnp/http://192.168.2.55:45397/UPnPServices/ContentDirectory/control@0977bc6a25a25d4cf21cd89c00c7caa5 in service upnp_browser Jul 15 12:34:38 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushQueue Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::saveQueue Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::updateTrackBlock Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrackBlock Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPlay Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::play index 5 Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::stop Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::play index undefined Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrack 5 Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::startPlaybackTimer Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrack 5 Jul 15 12:34:39 volumio volumio[1052]: info: [1721046879139] ControllerUPNPBrowser::clearAddPlayTrack Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand stop Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand stop took 1 milliseconds Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand clear Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces system playlist update Jul 15 12:34:39 volumio volumio[1052]: info: Ignoring MPD Status Update Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand clear took 2 milliseconds Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3" Jul 15 12:34:39 volumio volumio[1052]: error: updateQueue error: null Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 3ms Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces system playlist update Jul 15 12:34:39 volumio volumio[1052]: info: Ignoring MPD Status Update Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces system playlist update Jul 15 12:34:39 volumio volumio[1052]: info: Ignoring MPD Status Update Jul 15 12:34:39 volumio volumio[1052]: error: Upnp client error: Error: This socket has been ended by the other party Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3" Jul 15 12:34:39 volumio volumio[1052]: error: updateQueue error: null Jul 15 12:34:39 volumio volumio[1052]: error: updateQueue error: null Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces system playlist update Jul 15 12:34:39 volumio volumio[1052]: info: Ignoring MPD Status Update Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 553ms Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 553ms Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand add "http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3" took 2 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand play Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces system playlist update Jul 15 12:34:39 volumio volumio[1052]: info: Ignoring MPD Status Update Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 2ms Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand play took 1 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 2ms Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces system playlist update Jul 15 12:34:39 volumio volumio[1052]: info: Ignoring MPD Status Update Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 1ms Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces state update: player Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::getState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces state update: player Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::getState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces state update: player Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::getState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand status took 53 milliseconds Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces state update: player Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::getState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand status took 54 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand status took 53 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::servicePushState Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrack 5 Jul 15 12:34:39 volumio volumio[1052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":448,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","artist":null,"album":null,"uri":"http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","trackType":"mp3"} Jul 15 12:34:39 volumio volumio[1052]: verbose: CURRENT POSITION 5 Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState stateService play Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState currentStatus stop Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 60ms Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces state update: player Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::getState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:34:39 volumio volumio[1052]: info: Jul 15 12:34:39 volumio volumio[1052]: ---------------------------- MPD announces state update: player Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::getState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand status took 6 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand status took 2 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand status took 1 milliseconds Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseState Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::servicePushState Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrack 5 Jul 15 12:34:39 volumio volumio[1052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":193,"duration":448,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","artist":null,"album":null,"uri":"http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","trackType":"mp3"} Jul 15 12:34:39 volumio volumio[1052]: verbose: CURRENT POSITION 5 Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState stateService play Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:34:39 volumio volumio[1052]: info: Received an update from plugin. extracting info from payload Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::servicePushState Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrack 5 Jul 15 12:34:39 volumio volumio[1052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":448,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","artist":null,"album":null,"uri":"http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","trackType":"mp3"} Jul 15 12:34:39 volumio volumio[1052]: verbose: CURRENT POSITION 5 Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState stateService play Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:34:39 volumio volumio[1052]: info: Received an update from plugin. extracting info from payload Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 87ms Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 85ms Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 15 12:34:39 volumio volumio[1052]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:34:39 volumio volumio[1052]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::servicePushState Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrack 5 Jul 15 12:34:39 volumio volumio[1052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":448,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","artist":null,"album":null,"uri":"http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","trackType":"mp3"} Jul 15 12:34:39 volumio volumio[1052]: verbose: CURRENT POSITION 5 Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState stateService play Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:34:39 volumio volumio[1052]: info: Received an update from plugin. extracting info from payload Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::servicePushState Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrack 5 Jul 15 12:34:39 volumio volumio[1052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":448,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","artist":null,"album":null,"uri":"http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","trackType":"mp3"} Jul 15 12:34:39 volumio volumio[1052]: verbose: CURRENT POSITION 5 Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState stateService play Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:34:39 volumio volumio[1052]: info: Received an update from plugin. extracting info from payload Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: ControllerMpd::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::servicePushState Jul 15 12:34:39 volumio volumio[1052]: info: CorePlayQueue::getTrack 5 Jul 15 12:34:39 volumio volumio[1052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":448,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","artist":null,"album":null,"uri":"http://192.168.2.55:45397/MediaServer/AudioItems/T-f52340d463ee7d11dffe411072611afb.mp3%3Ftranscoding%3D1%3Bclientuuid%3D332e2c47-30ea-4b33-9727-d026fed61045%3Binfokey%3D00b9c7ac55d99d49439c4701a31c6a9e%3Bupdateid%3D30064771072%3Bfileext%3D.mp3","trackType":"mp3"} Jul 15 12:34:39 volumio volumio[1052]: verbose: CURRENT POSITION 5 Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState stateService play Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:34:39 volumio volumio[1052]: info: Received an update from plugin. extracting info from payload Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:39 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 64ms Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 60ms Jul 15 12:34:39 volumio volumio[1052]: info: ------------------------------ 60ms Jul 15 12:34:43 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:44 volumio volumio[1052]: info: VolumeController::SetAlsaVolume- Jul 15 12:34:44 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:44 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:34:44 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:44 volumio volumio[1052]: info: VolumeController::SetAlsaVolume- Jul 15 12:34:44 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:44 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:34:44 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:44 volumio volumio[1052]: info: VolumeController::SetAlsaVolume- Jul 15 12:34:44 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:44 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:34:44 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:46 volumio volumio[1052]: info: VolumeController::SetAlsaVolume0 Jul 15 12:34:46 volumio volumio[1052]: info: CoreStateMachine::pushState Jul 15 12:34:46 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:34:46 volumio volumio[1052]: info: CoreCommandRouter::volumioPushState Jul 15 12:34:48 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:49 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:49 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:51 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:51 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:52 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:52 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:53 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Jul 15 12:34:54 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:54 volumio volumio[1052]: info: Preload queue cleared Jul 15 12:34:55 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:34:55 volumio volumio[1052]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 12:34:55 volumio volumio[1052]: TypeError: Cannot read property 'length' of undefined Jul 15 12:34:55 volumio volumio[1052]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 15 12:34:55 volumio volumio[1052]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 15 12:34:55 volumio volumio[1052]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 15 12:34:55 volumio volumio[1052]: at Parser.emit (events.js:400:28) Jul 15 12:34:55 volumio volumio[1052]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 15 12:34:55 volumio volumio[1052]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 15 12:34:55 volumio volumio[1052]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 15 12:34:55 volumio volumio[1052]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 15 12:34:55 volumio volumio[1052]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 15 12:34:55 volumio volumio[1052]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 15 12:34:55 volumio volumio[1052]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 15 12:34:55 volumio volumio[1052]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 15 12:34:55 volumio volumio[1052]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 15 12:34:55 volumio volumio[1052]: at IncomingMessage.emit (events.js:412:35) Jul 15 12:34:55 volumio volumio[1052]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 15 12:34:55 volumio volumio[1052]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 15 12:34:55 volumio volumio[1052]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 12:34:55 volumio sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-15 12:33 Jul 15 12:34:55 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"