-- 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"