-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-02-18 12:33:38 CST. -- Feb 18 12:32:00 volumio77 dhcpcd[812]: vethbdb9724: carrier acquired Feb 18 12:32:00 volumio77 dhcpcd[812]: vethbdb9724: IAID be:19:d9:0b Feb 18 12:32:00 volumio77 dhcpcd[812]: vethbdb9724: soliciting a DHCP lease Feb 18 12:32:00 volumio77 dhcpcd[812]: vethbdb9724: soliciting an IPv6 router Feb 18 12:32:02 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:02 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:02 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:32:02 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:32:03 volumio77 volumio[1099]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 18 12:32:05 volumio77 dhcpcd[812]: vethbdb9724: probing for an IPv4LL address Feb 18 12:32:10 volumio77 dhcpcd[812]: vethbdb9724: using IPv4LL address 169.254.194.51 Feb 18 12:32:10 volumio77 dhcpcd[812]: vethbdb9724: adding route to 169.254.0.0/16 Feb 18 12:32:10 volumio77 avahi-daemon[700]: Joining mDNS multicast group on interface vethbdb9724.IPv4 with address 169.254.194.51. Feb 18 12:32:10 volumio77 avahi-daemon[700]: New relevant interface vethbdb9724.IPv4 for mDNS. Feb 18 12:32:10 volumio77 avahi-daemon[700]: Registering new address record for 169.254.194.51 on vethbdb9724.IPv4. Feb 18 12:32:11 volumio77 volumio[1099]: info: Discovery: adding 2490e78b-ac25-4fc9-aa4c-cb7f5a37b555 Feb 18 12:32:11 volumio77 volumio[1099]: info: Discovery: Found device Volumio77 Feb 18 12:32:11 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:11 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:11 volumio77 dockerd[1009]: time="2025-02-18T12:32:11.665612503+08:00" level=info msg="ignoring event" container=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Feb 18 12:32:11 volumio77 containerd[967]: time="2025-02-18T12:32:11.666502177+08:00" level=info msg="shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e Feb 18 12:32:11 volumio77 containerd[967]: time="2025-02-18T12:32:11.666961160+08:00" level=warning msg="cleaning up after shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e namespace=moby Feb 18 12:32:11 volumio77 containerd[967]: time="2025-02-18T12:32:11.667095863+08:00" level=info msg="cleaning up dead shim" Feb 18 12:32:11 volumio77 containerd[967]: time="2025-02-18T12:32:11.719623761+08:00" level=warning msg="cleanup warnings time=\"2025-02-18T12:32:11+08:00\" level=info msg=\"starting signal loop\" namespace=moby pid=23276 runtime=io.containerd.runc.v2\n" Feb 18 12:32:11 volumio77 dhcpcd[812]: vethbdb9724: carrier lost Feb 18 12:32:11 volumio77 kernel: docker0: port 1(vethbdb9724) entered disabled state Feb 18 12:32:11 volumio77 kernel: veth2245d77: renamed from eth0 Feb 18 12:32:11 volumio77 systemd-udevd[23304]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:11 volumio77 systemd-udevd[23304]: Using default interface naming scheme 'v240'. Feb 18 12:32:11 volumio77 avahi-daemon[700]: Interface vethbdb9724.IPv4 no longer relevant for mDNS. Feb 18 12:32:11 volumio77 kernel: docker0: port 1(vethbdb9724) entered disabled state Feb 18 12:32:11 volumio77 avahi-daemon[700]: Leaving mDNS multicast group on interface vethbdb9724.IPv4 with address 169.254.194.51. Feb 18 12:32:11 volumio77 kernel: device vethbdb9724 left promiscuous mode Feb 18 12:32:11 volumio77 kernel: docker0: port 1(vethbdb9724) entered disabled state Feb 18 12:32:11 volumio77 dhcpcd[812]: vethbdb9724: deleting route to 169.254.0.0/16 Feb 18 12:32:11 volumio77 avahi-daemon[700]: Withdrawing address record for 169.254.194.51 on vethbdb9724. Feb 18 12:32:11 volumio77 volumio[1099]: info: Discovery: A device disappeared from network Feb 18 12:32:11 volumio77 volumio[1099]: info: Discovery: Device volumio77 disappeared from network Feb 18 12:32:12 volumio77 systemd[1]: run-docker-netns-a622b74cf1fd.mount: Succeeded. Feb 18 12:32:12 volumio77 kernel: docker0: port 1(veth585647e) entered blocking state Feb 18 12:32:12 volumio77 kernel: docker0: port 1(veth585647e) entered disabled state Feb 18 12:32:12 volumio77 kernel: device veth585647e entered promiscuous mode Feb 18 12:32:12 volumio77 systemd-udevd[23314]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:12 volumio77 kernel: docker0: port 1(veth585647e) entered blocking state Feb 18 12:32:12 volumio77 kernel: docker0: port 1(veth585647e) entered forwarding state Feb 18 12:32:12 volumio77 systemd-udevd[23304]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:12 volumio77 systemd-udevd[23314]: Using default interface naming scheme 'v240'. Feb 18 12:32:12 volumio77 systemd-udevd[23304]: Could not generate persistent MAC address for vetha0caea1: No such file or directory Feb 18 12:32:12 volumio77 systemd-udevd[23314]: Could not generate persistent MAC address for veth585647e: No such file or directory Feb 18 12:32:12 volumio77 dhcpcd[812]: vethbdb9724: removing interface Feb 18 12:32:12 volumio77 dhcpcd[812]: vetha0caea1: waiting for carrier Feb 18 12:32:12 volumio77 dhcpcd[812]: veth585647e: IAID a6:29:8c:49 Feb 18 12:32:12 volumio77 dhcpcd[812]: veth585647e: adding address fe80::702b:a6ff:fe29:8c49 Feb 18 12:32:12 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:32:12 volumio77 dhcpcd[812]: veth585647e: carrier lost Feb 18 12:32:12 volumio77 containerd[967]: time="2025-02-18T12:32:12.446736654+08:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 18 12:32:12 volumio77 containerd[967]: time="2025-02-18T12:32:12.448478296+08:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 18 12:32:12 volumio77 containerd[967]: time="2025-02-18T12:32:12.448774493+08:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 18 12:32:12 volumio77 containerd[967]: time="2025-02-18T12:32:12.451257397+08:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e pid=23429 runtime=io.containerd.runc.v2 Feb 18 12:32:12 volumio77 dhcpcd[812]: veth585647e: carrier acquired Feb 18 12:32:12 volumio77 dhcpcd[812]: veth585647e: IAID a6:29:8c:49 Feb 18 12:32:12 volumio77 dhcpcd[812]: vetha0caea1: carrier acquired Feb 18 12:32:12 volumio77 dhcpcd[812]: vetha0caea1: IAID c5:78:b8:32 Feb 18 12:32:12 volumio77 dhcpcd[812]: vetha0caea1: adding address fe80::78b8:c5ff:fe78:b832 Feb 18 12:32:12 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:32:12 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:12 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:12 volumio77 dhcpcd[812]: veth585647e: soliciting an IPv6 router Feb 18 12:32:12 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Feb 18 12:32:12 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate from file: 48000 Feb 18 12:32:12 volumio77 volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 18 12:32:12 volumio77 dhcpcd[812]: veth585647e: soliciting a DHCP lease Feb 18 12:32:12 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Feb 18 12:32:12 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate from file: 48000 Feb 18 12:32:12 volumio77 volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 18 12:32:12 volumio77 volumio[1099]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 18 12:32:12 volumio77 volumio[1099]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 18 12:32:13 volumio77 dhcpcd[812]: vetha0caea1: carrier lost Feb 18 12:32:13 volumio77 kernel: docker0: port 1(veth585647e) entered disabled state Feb 18 12:32:13 volumio77 kernel: eth0: renamed from vetha0caea1 Feb 18 12:32:13 volumio77 kernel: docker0: port 1(veth585647e) entered blocking state Feb 18 12:32:13 volumio77 kernel: docker0: port 1(veth585647e) entered forwarding state Feb 18 12:32:13 volumio77 dhcpcd[812]: vetha0caea1: removing interface Feb 18 12:32:13 volumio77 volumio[1099]: info: Feb 18 12:32:13 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:13 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:13 volumio77 volumio[1099]: info: Feb 18 12:32:13 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:13 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:13 volumio77 volumio[1099]: info: Feb 18 12:32:13 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:13 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:13 volumio77 volumio[1099]: info: Feb 18 12:32:13 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:13 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:13 volumio77 volumio[1099]: info: sendMpdCommand status took 16 milliseconds Feb 18 12:32:13 volumio77 volumio[1099]: info: sendMpdCommand status took 14 milliseconds Feb 18 12:32:13 volumio77 volumio[1099]: info: sendMpdCommand status took 10 milliseconds Feb 18 12:32:13 volumio77 volumio[1099]: info: sendMpdCommand status took 7 milliseconds Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:13 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 18 12:32:13 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 18 12:32:13 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 18 12:32:13 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:13 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:13 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14448,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1638 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:13 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus pause Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:13 volumio77 dhcpcd[812]: veth585647e: carrier lost Feb 18 12:32:13 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14458,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1638 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:13 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:13 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:13 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14469,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1638 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:13 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:13 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:13 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14521,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1429 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:13 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:13 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:13 volumio77 volumio[1099]: info: ------------------------------ 235ms Feb 18 12:32:13 volumio77 volumio[1099]: info: ------------------------------ 231ms Feb 18 12:32:13 volumio77 volumio[1099]: info: ------------------------------ 229ms Feb 18 12:32:13 volumio77 volumio[1099]: info: ------------------------------ 227ms Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:13 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:13 volumio77 dhcpcd[812]: veth585647e: carrier acquired Feb 18 12:32:13 volumio77 dhcpcd[812]: veth585647e: IAID a6:29:8c:49 Feb 18 12:32:13 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:13 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:13 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:13 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:13 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:13 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:13 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:14 volumio77 dhcpcd[812]: veth585647e: soliciting a DHCP lease Feb 18 12:32:14 volumio77 dhcpcd[812]: veth585647e: soliciting an IPv6 router Feb 18 12:32:16 volumio77 volumio[1099]: info: Feb 18 12:32:16 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:16 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:16 volumio77 volumio[1099]: info: Feb 18 12:32:16 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:16 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:16 volumio77 volumio[1099]: info: Feb 18 12:32:16 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:16 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:16 volumio77 volumio[1099]: info: Feb 18 12:32:16 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:16 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:16 volumio77 volumio[1099]: info: sendMpdCommand status took 13 milliseconds Feb 18 12:32:16 volumio77 volumio[1099]: info: sendMpdCommand status took 10 milliseconds Feb 18 12:32:16 volumio77 volumio[1099]: info: sendMpdCommand status took 7 milliseconds Feb 18 12:32:16 volumio77 volumio[1099]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:16 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:32:16 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:32:16 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:32:16 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:16 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:16 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:16 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17650,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1550 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:16 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService pause Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:16 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:16 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17650,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1550 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:16 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService pause Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:16 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:16 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17650,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1550 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:16 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService pause Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:16 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:16 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17650,"duration":229,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1550 Kbps","isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:16 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService pause Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:16 volumio77 volumio[1099]: info: ------------------------------ 101ms Feb 18 12:32:16 volumio77 volumio[1099]: info: ------------------------------ 98ms Feb 18 12:32:16 volumio77 volumio[1099]: info: ------------------------------ 96ms Feb 18 12:32:16 volumio77 volumio[1099]: info: ------------------------------ 94ms Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:16 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:16 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:16 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:16 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:16 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:16 volumio77 volumio[1099]: info: camilladsp spawned new process with pid 23583, instance 1, run: true Feb 18 12:32:19 volumio77 dhcpcd[812]: veth585647e: probing for an IPv4LL address Feb 18 12:32:22 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:22 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:22 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:32:22 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:32:23 volumio77 dhcpcd[812]: veth585647e: using IPv4LL address 169.254.183.115 Feb 18 12:32:23 volumio77 dhcpcd[812]: veth585647e: adding route to 169.254.0.0/16 Feb 18 12:32:23 volumio77 avahi-daemon[700]: Joining mDNS multicast group on interface veth585647e.IPv4 with address 169.254.183.115. Feb 18 12:32:23 volumio77 avahi-daemon[700]: New relevant interface veth585647e.IPv4 for mDNS. Feb 18 12:32:23 volumio77 avahi-daemon[700]: Registering new address record for 169.254.183.115 on veth585647e.IPv4. Feb 18 12:32:24 volumio77 volumio[1099]: info: Discovery: adding 2490e78b-ac25-4fc9-aa4c-cb7f5a37b555 Feb 18 12:32:24 volumio77 volumio[1099]: info: Discovery: Found device Volumio77 Feb 18 12:32:24 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:24 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:25 volumio77 ntpd[1067]: Listen normally on 10 veth585647e 169.254.183.115:123 Feb 18 12:32:25 volumio77 ntpd[1067]: new interface(s) found: waking up resolver Feb 18 12:32:25 volumio77 containerd[967]: time="2025-02-18T12:32:25.379351763+08:00" level=info msg="shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e Feb 18 12:32:25 volumio77 containerd[967]: time="2025-02-18T12:32:25.379932033+08:00" level=warning msg="cleaning up after shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e namespace=moby Feb 18 12:32:25 volumio77 containerd[967]: time="2025-02-18T12:32:25.380108234+08:00" level=info msg="cleaning up dead shim" Feb 18 12:32:25 volumio77 dockerd[1009]: time="2025-02-18T12:32:25.379710083+08:00" level=info msg="ignoring event" container=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Feb 18 12:32:25 volumio77 containerd[967]: time="2025-02-18T12:32:25.433276273+08:00" level=warning msg="cleanup warnings time=\"2025-02-18T12:32:25+08:00\" level=info msg=\"starting signal loop\" namespace=moby pid=23642 runtime=io.containerd.runc.v2\n" Feb 18 12:32:25 volumio77 dhcpcd[812]: veth585647e: carrier lost Feb 18 12:32:25 volumio77 kernel: docker0: port 1(veth585647e) entered disabled state Feb 18 12:32:25 volumio77 kernel: vetha0caea1: renamed from eth0 Feb 18 12:32:25 volumio77 systemd-udevd[23675]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:25 volumio77 systemd-udevd[23675]: Using default interface naming scheme 'v240'. Feb 18 12:32:25 volumio77 avahi-daemon[700]: Interface veth585647e.IPv4 no longer relevant for mDNS. Feb 18 12:32:25 volumio77 avahi-daemon[700]: Leaving mDNS multicast group on interface veth585647e.IPv4 with address 169.254.183.115. Feb 18 12:32:25 volumio77 kernel: docker0: port 1(veth585647e) entered disabled state Feb 18 12:32:25 volumio77 kernel: device veth585647e left promiscuous mode Feb 18 12:32:25 volumio77 kernel: docker0: port 1(veth585647e) entered disabled state Feb 18 12:32:25 volumio77 avahi-daemon[700]: Withdrawing address record for 169.254.183.115 on veth585647e. Feb 18 12:32:25 volumio77 dhcpcd[812]: veth585647e: deleting route to 169.254.0.0/16 Feb 18 12:32:25 volumio77 volumio[1099]: info: Discovery: A device disappeared from network Feb 18 12:32:25 volumio77 volumio[1099]: info: Discovery: Device volumio77 disappeared from network Feb 18 12:32:25 volumio77 systemd[1]: run-docker-netns-f0444b6b979b.mount: Succeeded. Feb 18 12:32:25 volumio77 systemd-udevd[23675]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:25 volumio77 kernel: docker0: port 1(veth1f3ee40) entered blocking state Feb 18 12:32:25 volumio77 kernel: docker0: port 1(veth1f3ee40) entered disabled state Feb 18 12:32:25 volumio77 kernel: device veth1f3ee40 entered promiscuous mode Feb 18 12:32:25 volumio77 kernel: docker0: port 1(veth1f3ee40) entered blocking state Feb 18 12:32:25 volumio77 kernel: docker0: port 1(veth1f3ee40) entered forwarding state Feb 18 12:32:25 volumio77 systemd-udevd[23682]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:25 volumio77 systemd-udevd[23675]: Could not generate persistent MAC address for vethe4f68c9: No such file or directory Feb 18 12:32:25 volumio77 systemd-udevd[23682]: Using default interface naming scheme 'v240'. Feb 18 12:32:25 volumio77 systemd-udevd[23682]: Could not generate persistent MAC address for veth1f3ee40: No such file or directory Feb 18 12:32:26 volumio77 dhcpcd[812]: veth585647e: removing interface Feb 18 12:32:26 volumio77 dhcpcd[812]: vethe4f68c9: waiting for carrier Feb 18 12:32:26 volumio77 dhcpcd[812]: veth1f3ee40: IAID a9:f0:00:38 Feb 18 12:32:26 volumio77 dhcpcd[812]: veth1f3ee40: adding address fe80::7896:a9ff:fef0:38 Feb 18 12:32:26 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:32:26 volumio77 dhcpcd[812]: veth1f3ee40: carrier lost Feb 18 12:32:26 volumio77 containerd[967]: time="2025-02-18T12:32:26.283406866+08:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 18 12:32:26 volumio77 containerd[967]: time="2025-02-18T12:32:26.284762314+08:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 18 12:32:26 volumio77 containerd[967]: time="2025-02-18T12:32:26.285186423+08:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 18 12:32:26 volumio77 containerd[967]: time="2025-02-18T12:32:26.287490001+08:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e pid=23807 runtime=io.containerd.runc.v2 Feb 18 12:32:26 volumio77 dhcpcd[812]: veth1f3ee40: carrier acquired Feb 18 12:32:26 volumio77 dhcpcd[812]: veth1f3ee40: IAID a9:f0:00:38 Feb 18 12:32:26 volumio77 dhcpcd[812]: vethe4f68c9: carrier acquired Feb 18 12:32:26 volumio77 dhcpcd[812]: vethe4f68c9: IAID 1f:2b:79:55 Feb 18 12:32:26 volumio77 dhcpcd[812]: vethe4f68c9: adding address fe80::a074:1fff:fe2b:7955 Feb 18 12:32:26 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:32:26 volumio77 dhcpcd[812]: vethe4f68c9: soliciting a DHCP lease Feb 18 12:32:26 volumio77 dhcpcd[812]: vethe4f68c9: soliciting an IPv6 router Feb 18 12:32:26 volumio77 dhcpcd[812]: vethe4f68c9: carrier lost Feb 18 12:32:26 volumio77 kernel: docker0: port 1(veth1f3ee40) entered disabled state Feb 18 12:32:26 volumio77 kernel: eth0: renamed from vethe4f68c9 Feb 18 12:32:26 volumio77 kernel: docker0: port 1(veth1f3ee40) entered blocking state Feb 18 12:32:26 volumio77 kernel: docker0: port 1(veth1f3ee40) entered forwarding state Feb 18 12:32:27 volumio77 dhcpcd[812]: vethe4f68c9: removing interface Feb 18 12:32:27 volumio77 dhcpcd[812]: veth1f3ee40: soliciting an IPv6 router Feb 18 12:32:27 volumio77 dhcpcd[812]: veth1f3ee40: soliciting a DHCP lease Feb 18 12:32:27 volumio77 dhcpcd[812]: veth1f3ee40: carrier lost Feb 18 12:32:27 volumio77 dhcpcd[812]: veth1f3ee40: carrier acquired Feb 18 12:32:27 volumio77 dhcpcd[812]: veth1f3ee40: IAID a9:f0:00:38 Feb 18 12:32:28 volumio77 dhcpcd[812]: veth1f3ee40: soliciting an IPv6 router Feb 18 12:32:28 volumio77 dhcpcd[812]: veth1f3ee40: soliciting a DHCP lease Feb 18 12:32:29 volumio77 ntpd[1067]: Deleting interface #10 veth585647e, 169.254.183.115#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Feb 18 12:32:32 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:32 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:33 volumio77 dhcpcd[812]: veth1f3ee40: probing for an IPv4LL address Feb 18 12:32:37 volumio77 dhcpcd[812]: veth1f3ee40: using IPv4LL address 169.254.26.231 Feb 18 12:32:37 volumio77 dhcpcd[812]: veth1f3ee40: adding route to 169.254.0.0/16 Feb 18 12:32:37 volumio77 avahi-daemon[700]: Joining mDNS multicast group on interface veth1f3ee40.IPv4 with address 169.254.26.231. Feb 18 12:32:37 volumio77 avahi-daemon[700]: New relevant interface veth1f3ee40.IPv4 for mDNS. Feb 18 12:32:37 volumio77 avahi-daemon[700]: Registering new address record for 169.254.26.231 on veth1f3ee40.IPv4. Feb 18 12:32:38 volumio77 volumio[1099]: info: Discovery: adding 2490e78b-ac25-4fc9-aa4c-cb7f5a37b555 Feb 18 12:32:38 volumio77 volumio[1099]: info: Discovery: Found device Volumio77 Feb 18 12:32:38 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:38 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:39 volumio77 ntpd[1067]: Listen normally on 11 veth1f3ee40 169.254.26.231:123 Feb 18 12:32:39 volumio77 ntpd[1067]: new interface(s) found: waking up resolver Feb 18 12:32:39 volumio77 containerd[967]: time="2025-02-18T12:32:39.370024659+08:00" level=info msg="shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e Feb 18 12:32:39 volumio77 containerd[967]: time="2025-02-18T12:32:39.370650218+08:00" level=warning msg="cleaning up after shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e namespace=moby Feb 18 12:32:39 volumio77 containerd[967]: time="2025-02-18T12:32:39.370868418+08:00" level=info msg="cleaning up dead shim" Feb 18 12:32:39 volumio77 dockerd[1009]: time="2025-02-18T12:32:39.370341855+08:00" level=info msg="ignoring event" container=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Feb 18 12:32:39 volumio77 containerd[967]: time="2025-02-18T12:32:39.425480607+08:00" level=warning msg="cleanup warnings time=\"2025-02-18T12:32:39+08:00\" level=info msg=\"starting signal loop\" namespace=moby pid=24040 runtime=io.containerd.runc.v2\n" Feb 18 12:32:39 volumio77 dhcpcd[812]: veth1f3ee40: carrier lost Feb 18 12:32:39 volumio77 kernel: docker0: port 1(veth1f3ee40) entered disabled state Feb 18 12:32:39 volumio77 kernel: vethe4f68c9: renamed from eth0 Feb 18 12:32:39 volumio77 systemd-udevd[24068]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:39 volumio77 systemd-udevd[24068]: Using default interface naming scheme 'v240'. Feb 18 12:32:39 volumio77 avahi-daemon[700]: Interface veth1f3ee40.IPv4 no longer relevant for mDNS. Feb 18 12:32:39 volumio77 kernel: docker0: port 1(veth1f3ee40) entered disabled state Feb 18 12:32:39 volumio77 avahi-daemon[700]: Leaving mDNS multicast group on interface veth1f3ee40.IPv4 with address 169.254.26.231. Feb 18 12:32:39 volumio77 kernel: device veth1f3ee40 left promiscuous mode Feb 18 12:32:39 volumio77 kernel: docker0: port 1(veth1f3ee40) entered disabled state Feb 18 12:32:39 volumio77 avahi-daemon[700]: Withdrawing address record for 169.254.26.231 on veth1f3ee40. Feb 18 12:32:39 volumio77 dhcpcd[812]: veth1f3ee40: deleting route to 169.254.0.0/16 Feb 18 12:32:39 volumio77 volumio[1099]: info: Discovery: A device disappeared from network Feb 18 12:32:39 volumio77 volumio[1099]: info: Discovery: Device volumio77 disappeared from network Feb 18 12:32:39 volumio77 systemd[1]: run-docker-netns-2a75bfcb9e60.mount: Succeeded. Feb 18 12:32:39 volumio77 systemd-udevd[24079]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:39 volumio77 systemd-udevd[24068]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:39 volumio77 systemd-udevd[24079]: Using default interface naming scheme 'v240'. Feb 18 12:32:39 volumio77 systemd-udevd[24068]: Could not generate persistent MAC address for veth8ac055d: No such file or directory Feb 18 12:32:39 volumio77 systemd-udevd[24079]: Could not generate persistent MAC address for vetha0f30ed: No such file or directory Feb 18 12:32:39 volumio77 kernel: docker0: port 1(vetha0f30ed) entered blocking state Feb 18 12:32:39 volumio77 kernel: docker0: port 1(vetha0f30ed) entered disabled state Feb 18 12:32:39 volumio77 kernel: device vetha0f30ed entered promiscuous mode Feb 18 12:32:39 volumio77 kernel: docker0: port 1(vetha0f30ed) entered blocking state Feb 18 12:32:39 volumio77 kernel: docker0: port 1(vetha0f30ed) entered forwarding state Feb 18 12:32:39 volumio77 dhcpcd[812]: veth1f3ee40: removing interface Feb 18 12:32:40 volumio77 dhcpcd[812]: veth8ac055d: waiting for carrier Feb 18 12:32:40 volumio77 dhcpcd[812]: vetha0f30ed: IAID 94:b9:a9:ba Feb 18 12:32:40 volumio77 dhcpcd[812]: vetha0f30ed: adding address fe80::28f4:94ff:feb9:a9ba Feb 18 12:32:40 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:32:40 volumio77 dhcpcd[812]: vetha0f30ed: carrier lost Feb 18 12:32:40 volumio77 dhcpcd[812]: vetha0f30ed: carrier acquired Feb 18 12:32:40 volumio77 containerd[967]: time="2025-02-18T12:32:40.225838836+08:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 18 12:32:40 volumio77 containerd[967]: time="2025-02-18T12:32:40.226904670+08:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 18 12:32:40 volumio77 containerd[967]: time="2025-02-18T12:32:40.227167826+08:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 18 12:32:40 volumio77 containerd[967]: time="2025-02-18T12:32:40.228942758+08:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e pid=24204 runtime=io.containerd.runc.v2 Feb 18 12:32:40 volumio77 dhcpcd[812]: vetha0f30ed: IAID 94:b9:a9:ba Feb 18 12:32:40 volumio77 dhcpcd[812]: veth8ac055d: carrier acquired Feb 18 12:32:40 volumio77 dhcpcd[812]: veth8ac055d: IAID 23:89:c6:a3 Feb 18 12:32:40 volumio77 dhcpcd[812]: veth8ac055d: adding address fe80::b425:23ff:fe89:c6a3 Feb 18 12:32:40 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:32:40 volumio77 dhcpcd[812]: vetha0f30ed: soliciting an IPv6 router Feb 18 12:32:40 volumio77 dhcpcd[812]: veth8ac055d: carrier lost Feb 18 12:32:40 volumio77 kernel: docker0: port 1(vetha0f30ed) entered disabled state Feb 18 12:32:40 volumio77 kernel: eth0: renamed from veth8ac055d Feb 18 12:32:40 volumio77 kernel: docker0: port 1(vetha0f30ed) entered blocking state Feb 18 12:32:40 volumio77 kernel: docker0: port 1(vetha0f30ed) entered forwarding state Feb 18 12:32:40 volumio77 dhcpcd[812]: vetha0f30ed: soliciting a DHCP lease Feb 18 12:32:40 volumio77 dhcpcd[812]: veth8ac055d: removing interface Feb 18 12:32:40 volumio77 dhcpcd[812]: vetha0f30ed: carrier lost Feb 18 12:32:41 volumio77 dhcpcd[812]: vetha0f30ed: carrier acquired Feb 18 12:32:41 volumio77 dhcpcd[812]: vetha0f30ed: IAID 94:b9:a9:ba Feb 18 12:32:41 volumio77 dhcpcd[812]: vetha0f30ed: soliciting a DHCP lease Feb 18 12:32:42 volumio77 dhcpcd[812]: vetha0f30ed: soliciting an IPv6 router Feb 18 12:32:42 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:42 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:42 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:32:42 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:32:43 volumio77 ntpd[1067]: Deleting interface #11 veth1f3ee40, 169.254.26.231#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Feb 18 12:32:46 volumio77 dhcpcd[812]: vetha0f30ed: probing for an IPv4LL address Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 15 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 12 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 8 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:32:47 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:47 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::play index undefined Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:47 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: No code Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:47 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: No code Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/271.flac","trackType":"flac"} Feb 18 12:32:47 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: No code Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: [1739853167334] ControllerUPNPBrowser::clearAddPlayTrack Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 185ms Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 182ms Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 180ms Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 179ms Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand stop took 27 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:47 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand clear took 67 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.24:8201/MediaItems/275.mp3" Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:32:47 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:32:47 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:32:47 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 34ms Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.24:8201/MediaItems/275.mp3" Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 30ms Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 28ms Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 11ms Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand add "http://192.168.3.24:8201/MediaItems/275.mp3" took 6 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 19ms Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand play took 15 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 15ms Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 11ms Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 11ms Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 18 12:32:47 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 18 12:32:47 volumio77 volumio[1099]: info: camilladsp stopping service pid 23583... Feb 18 12:32:47 volumio77 volumio[1099]: info: camilladsp service terminated, instance 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 18 12:32:47 volumio77 volumio[1099]: info: camilladsp service started and running in background, instance 1 Feb 18 12:32:47 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 18 12:32:47 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 18 12:32:47 volumio77 volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:47 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 170 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 168 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 166 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 160 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: Feb 18 12:32:47 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:47 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:47 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:47 volumio77 volumio[1099]: info: sendMpdCommand status took 50 milliseconds Feb 18 12:32:47 volumio77 volumio[1099]: info: ------------------------------ 65ms Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand status took 48 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 47 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 46 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 45 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 42 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand status took 34 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand status took 30 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand status took 25 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 345ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 374ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 373ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 371ms Feb 18 12:32:48 volumio77 volumio[1099]: info: Feb 18 12:32:48 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:48 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:48 volumio77 volumio[1099]: info: Feb 18 12:32:48 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:48 volumio77 volumio[1099]: info: Feb 18 12:32:48 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:48 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:48 volumio77 volumio[1099]: info: Feb 18 12:32:48 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:48 volumio77 volumio[1099]: info: Feb 18 12:32:48 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:32:48 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:32:48 volumio77 volumio[1099]: info: Feb 18 12:32:48 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:32:48 volumio77 volumio[1099]: error: FusionDsp - WebSocket error: [object Object] Feb 18 12:32:48 volumio77 volumio[1099]: info: camilladsp spawned new process with pid 24361, instance 1, run: true Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 192 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 190 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 182 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 180 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 178 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 64ms Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand status took 61 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 60ms Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand status took 58 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 56ms Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand status took 54 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 479ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 476ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 445ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 441ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 436ms Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 392 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 392 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 391 milliseconds Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:32:48 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:32:48 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:32:48 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 565ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 563ms Feb 18 12:32:48 volumio77 volumio[1099]: info: ------------------------------ 560ms Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:48 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:32:51 volumio77 dhcpcd[812]: vetha0f30ed: using IPv4LL address 169.254.91.70 Feb 18 12:32:51 volumio77 avahi-daemon[700]: Joining mDNS multicast group on interface vetha0f30ed.IPv4 with address 169.254.91.70. Feb 18 12:32:51 volumio77 avahi-daemon[700]: New relevant interface vetha0f30ed.IPv4 for mDNS. Feb 18 12:32:51 volumio77 avahi-daemon[700]: Registering new address record for 169.254.91.70 on vetha0f30ed.IPv4. Feb 18 12:32:51 volumio77 dhcpcd[812]: vetha0f30ed: adding route to 169.254.0.0/16 Feb 18 12:32:52 volumio77 volumio[1099]: info: Discovery: adding 2490e78b-ac25-4fc9-aa4c-cb7f5a37b555 Feb 18 12:32:52 volumio77 volumio[1099]: info: Discovery: Found device Volumio77 Feb 18 12:32:52 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:52 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:32:53 volumio77 ntpd[1067]: Listen normally on 12 vetha0f30ed 169.254.91.70:123 Feb 18 12:32:53 volumio77 ntpd[1067]: new interface(s) found: waking up resolver Feb 18 12:32:53 volumio77 containerd[967]: time="2025-02-18T12:32:53.034717280+08:00" level=info msg="shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e Feb 18 12:32:53 volumio77 containerd[967]: time="2025-02-18T12:32:53.035300424+08:00" level=warning msg="cleaning up after shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e namespace=moby Feb 18 12:32:53 volumio77 containerd[967]: time="2025-02-18T12:32:53.035485125+08:00" level=info msg="cleaning up dead shim" Feb 18 12:32:53 volumio77 dockerd[1009]: time="2025-02-18T12:32:53.035174679+08:00" level=info msg="ignoring event" container=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Feb 18 12:32:53 volumio77 containerd[967]: time="2025-02-18T12:32:53.090667957+08:00" level=warning msg="cleanup warnings time=\"2025-02-18T12:32:53+08:00\" level=info msg=\"starting signal loop\" namespace=moby pid=24417 runtime=io.containerd.runc.v2\n" Feb 18 12:32:53 volumio77 dhcpcd[812]: vetha0f30ed: carrier lost Feb 18 12:32:53 volumio77 kernel: docker0: port 1(vetha0f30ed) entered disabled state Feb 18 12:32:53 volumio77 kernel: veth8ac055d: renamed from eth0 Feb 18 12:32:53 volumio77 systemd-udevd[24450]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:53 volumio77 systemd-udevd[24450]: Using default interface naming scheme 'v240'. Feb 18 12:32:53 volumio77 avahi-daemon[700]: Interface vetha0f30ed.IPv4 no longer relevant for mDNS. Feb 18 12:32:53 volumio77 avahi-daemon[700]: Leaving mDNS multicast group on interface vetha0f30ed.IPv4 with address 169.254.91.70. Feb 18 12:32:53 volumio77 kernel: docker0: port 1(vetha0f30ed) entered disabled state Feb 18 12:32:53 volumio77 kernel: device vetha0f30ed left promiscuous mode Feb 18 12:32:53 volumio77 kernel: docker0: port 1(vetha0f30ed) entered disabled state Feb 18 12:32:53 volumio77 avahi-daemon[700]: Withdrawing address record for 169.254.91.70 on vetha0f30ed. Feb 18 12:32:53 volumio77 dhcpcd[812]: vetha0f30ed: deleting route to 169.254.0.0/16 Feb 18 12:32:53 volumio77 volumio[1099]: info: Discovery: A device disappeared from network Feb 18 12:32:53 volumio77 volumio[1099]: info: Discovery: Device volumio77 disappeared from network Feb 18 12:32:53 volumio77 systemd[1]: run-docker-netns-19675445c5ac.mount: Succeeded. Feb 18 12:32:53 volumio77 systemd-udevd[24458]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:53 volumio77 systemd-udevd[24458]: Using default interface naming scheme 'v240'. Feb 18 12:32:53 volumio77 systemd-udevd[24458]: Could not generate persistent MAC address for vethcc1bbb7: No such file or directory Feb 18 12:32:53 volumio77 systemd-udevd[24450]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:32:53 volumio77 systemd-udevd[24450]: Could not generate persistent MAC address for veth5d469cf: No such file or directory Feb 18 12:32:53 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered blocking state Feb 18 12:32:53 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered disabled state Feb 18 12:32:53 volumio77 kernel: device vethcc1bbb7 entered promiscuous mode Feb 18 12:32:53 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered blocking state Feb 18 12:32:53 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered forwarding state Feb 18 12:32:53 volumio77 dhcpcd[812]: vetha0f30ed: removing interface Feb 18 12:32:53 volumio77 dhcpcd[812]: veth5d469cf: waiting for carrier Feb 18 12:32:53 volumio77 dhcpcd[812]: vethcc1bbb7: IAID a0:77:a0:dd Feb 18 12:32:53 volumio77 dhcpcd[812]: vethcc1bbb7: adding address fe80::d4c9:a0ff:fe77:a0dd Feb 18 12:32:53 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:32:53 volumio77 dhcpcd[812]: vethcc1bbb7: carrier lost Feb 18 12:32:53 volumio77 containerd[967]: time="2025-02-18T12:32:53.844281293+08:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 18 12:32:53 volumio77 containerd[967]: time="2025-02-18T12:32:53.845462122+08:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 18 12:32:53 volumio77 containerd[967]: time="2025-02-18T12:32:53.847188347+08:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 18 12:32:53 volumio77 dhcpcd[812]: vethcc1bbb7: carrier acquired Feb 18 12:32:53 volumio77 containerd[967]: time="2025-02-18T12:32:53.849267642+08:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e pid=24579 runtime=io.containerd.runc.v2 Feb 18 12:32:53 volumio77 dhcpcd[812]: vethcc1bbb7: IAID a0:77:a0:dd Feb 18 12:32:53 volumio77 dhcpcd[812]: veth5d469cf: carrier acquired Feb 18 12:32:53 volumio77 dhcpcd[812]: veth5d469cf: IAID 40:76:f9:b5 Feb 18 12:32:53 volumio77 dhcpcd[812]: veth5d469cf: adding address fe80::cab:40ff:fe76:f9b5 Feb 18 12:32:53 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:32:54 volumio77 dhcpcd[812]: vethcc1bbb7: soliciting a DHCP lease Feb 18 12:32:54 volumio77 dhcpcd[812]: veth5d469cf: soliciting a DHCP lease Feb 18 12:32:54 volumio77 dhcpcd[812]: vethcc1bbb7: soliciting an IPv6 router Feb 18 12:32:54 volumio77 dhcpcd[812]: veth5d469cf: carrier lost Feb 18 12:32:54 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered disabled state Feb 18 12:32:54 volumio77 kernel: eth0: renamed from veth5d469cf Feb 18 12:32:54 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered blocking state Feb 18 12:32:54 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered forwarding state Feb 18 12:32:54 volumio77 dhcpcd[812]: veth5d469cf: removing interface Feb 18 12:32:55 volumio77 dhcpcd[812]: vethcc1bbb7: carrier lost Feb 18 12:32:55 volumio77 dhcpcd[812]: vethcc1bbb7: carrier acquired Feb 18 12:32:55 volumio77 dhcpcd[812]: vethcc1bbb7: IAID a0:77:a0:dd Feb 18 12:32:55 volumio77 dhcpcd[812]: vethcc1bbb7: soliciting an IPv6 router Feb 18 12:32:55 volumio77 dhcpcd[812]: vethcc1bbb7: soliciting a DHCP lease Feb 18 12:32:57 volumio77 ntpd[1067]: Deleting interface #12 vetha0f30ed, 169.254.91.70#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Feb 18 12:33:00 volumio77 volumio[1099]: info: Feb 18 12:33:00 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:00 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:00 volumio77 volumio[1099]: info: Feb 18 12:33:00 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:00 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:00 volumio77 volumio[1099]: info: Feb 18 12:33:00 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:00 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:00 volumio77 volumio[1099]: info: Feb 18 12:33:00 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:00 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:00 volumio77 volumio[1099]: info: sendMpdCommand status took 12 milliseconds Feb 18 12:33:00 volumio77 volumio[1099]: info: sendMpdCommand status took 8 milliseconds Feb 18 12:33:00 volumio77 volumio[1099]: info: sendMpdCommand status took 6 milliseconds Feb 18 12:33:00 volumio77 volumio[1099]: info: sendMpdCommand status took 4 milliseconds Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:00 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 18 12:33:00 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 18 12:33:00 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 18 12:33:00 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:00 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:00 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:33:00 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13611,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:33:00 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService pause Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:33:00 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:33:00 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13611,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:33:00 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService pause Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:33:00 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:33:00 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13611,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:33:00 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService pause Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:33:00 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:33:00 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13611,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:33:00 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService pause Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:33:00 volumio77 volumio[1099]: info: ------------------------------ 95ms Feb 18 12:33:00 volumio77 volumio[1099]: info: ------------------------------ 93ms Feb 18 12:33:00 volumio77 volumio[1099]: info: ------------------------------ 91ms Feb 18 12:33:00 volumio77 volumio[1099]: info: ------------------------------ 91ms Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:33:00 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:33:00 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:00 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:00 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:00 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:00 volumio77 dhcpcd[812]: vethcc1bbb7: probing for an IPv4LL address Feb 18 12:33:00 volumio77 volumio[1099]: info: camilladsp spawned new process with pid 24751, instance 1, run: true Feb 18 12:33:02 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:33:02 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:33:02 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:33:06 volumio77 dhcpcd[812]: vethcc1bbb7: using IPv4LL address 169.254.221.209 Feb 18 12:33:06 volumio77 dhcpcd[812]: vethcc1bbb7: adding route to 169.254.0.0/16 Feb 18 12:33:06 volumio77 avahi-daemon[700]: Joining mDNS multicast group on interface vethcc1bbb7.IPv4 with address 169.254.221.209. Feb 18 12:33:06 volumio77 avahi-daemon[700]: New relevant interface vethcc1bbb7.IPv4 for mDNS. Feb 18 12:33:06 volumio77 avahi-daemon[700]: Registering new address record for 169.254.221.209 on vethcc1bbb7.IPv4. Feb 18 12:33:07 volumio77 containerd[967]: time="2025-02-18T12:33:07.304250399+08:00" level=info msg="shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e Feb 18 12:33:07 volumio77 containerd[967]: time="2025-02-18T12:33:07.307958631+08:00" level=warning msg="cleaning up after shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e namespace=moby Feb 18 12:33:07 volumio77 containerd[967]: time="2025-02-18T12:33:07.308197830+08:00" level=info msg="cleaning up dead shim" Feb 18 12:33:07 volumio77 dockerd[1009]: time="2025-02-18T12:33:07.304142611+08:00" level=info msg="ignoring event" container=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Feb 18 12:33:07 volumio77 containerd[967]: time="2025-02-18T12:33:07.367710201+08:00" level=warning msg="cleanup warnings time=\"2025-02-18T12:33:07+08:00\" level=info msg=\"starting signal loop\" namespace=moby pid=24795 runtime=io.containerd.runc.v2\n" Feb 18 12:33:07 volumio77 volumio[1099]: info: Discovery: adding 2490e78b-ac25-4fc9-aa4c-cb7f5a37b555 Feb 18 12:33:07 volumio77 volumio[1099]: info: Discovery: Found device Volumio77 Feb 18 12:33:07 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:33:07 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered disabled state Feb 18 12:33:07 volumio77 kernel: veth5d469cf: renamed from eth0 Feb 18 12:33:07 volumio77 dhcpcd[812]: vethcc1bbb7: carrier lost Feb 18 12:33:07 volumio77 systemd-udevd[24828]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:07 volumio77 systemd-udevd[24828]: Using default interface naming scheme 'v240'. Feb 18 12:33:07 volumio77 avahi-daemon[700]: Interface vethcc1bbb7.IPv4 no longer relevant for mDNS. Feb 18 12:33:07 volumio77 avahi-daemon[700]: Leaving mDNS multicast group on interface vethcc1bbb7.IPv4 with address 169.254.221.209. Feb 18 12:33:07 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered disabled state Feb 18 12:33:07 volumio77 kernel: device vethcc1bbb7 left promiscuous mode Feb 18 12:33:07 volumio77 kernel: docker0: port 1(vethcc1bbb7) entered disabled state Feb 18 12:33:07 volumio77 dhcpcd[812]: vethcc1bbb7: deleting route to 169.254.0.0/16 Feb 18 12:33:07 volumio77 avahi-daemon[700]: Withdrawing address record for 169.254.221.209 on vethcc1bbb7. Feb 18 12:33:07 volumio77 volumio[1099]: info: Discovery: A device disappeared from network Feb 18 12:33:07 volumio77 volumio[1099]: info: Discovery: Device volumio77 disappeared from network Feb 18 12:33:07 volumio77 systemd[1]: run-docker-netns-68d2df4f13bc.mount: Succeeded. Feb 18 12:33:07 volumio77 systemd-udevd[24835]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:07 volumio77 kernel: docker0: port 1(veth3e2c071) entered blocking state Feb 18 12:33:07 volumio77 kernel: docker0: port 1(veth3e2c071) entered disabled state Feb 18 12:33:07 volumio77 kernel: device veth3e2c071 entered promiscuous mode Feb 18 12:33:07 volumio77 systemd-udevd[24835]: Using default interface naming scheme 'v240'. Feb 18 12:33:07 volumio77 systemd-udevd[24835]: Could not generate persistent MAC address for vethb254f7a: No such file or directory Feb 18 12:33:07 volumio77 kernel: docker0: port 1(veth3e2c071) entered blocking state Feb 18 12:33:07 volumio77 kernel: docker0: port 1(veth3e2c071) entered forwarding state Feb 18 12:33:07 volumio77 systemd-udevd[24836]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:07 volumio77 systemd-udevd[24836]: Using default interface naming scheme 'v240'. Feb 18 12:33:07 volumio77 systemd-udevd[24836]: Could not generate persistent MAC address for veth3e2c071: No such file or directory Feb 18 12:33:07 volumio77 dhcpcd[812]: vethcc1bbb7: removing interface Feb 18 12:33:08 volumio77 dhcpcd[812]: vethb254f7a: waiting for carrier Feb 18 12:33:08 volumio77 dhcpcd[812]: veth3e2c071: IAID c8:e7:76:e2 Feb 18 12:33:08 volumio77 dhcpcd[812]: veth3e2c071: adding address fe80::f82e:c8ff:fee7:76e2 Feb 18 12:33:08 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:33:08 volumio77 dhcpcd[812]: veth3e2c071: carrier lost Feb 18 12:33:08 volumio77 dhcpcd[812]: veth3e2c071: carrier acquired Feb 18 12:33:08 volumio77 dhcpcd[812]: veth3e2c071: IAID c8:e7:76:e2 Feb 18 12:33:08 volumio77 dhcpcd[812]: vethb254f7a: carrier acquired Feb 18 12:33:08 volumio77 dhcpcd[812]: vethb254f7a: IAID 11:26:d4:31 Feb 18 12:33:08 volumio77 dhcpcd[812]: vethb254f7a: adding address fe80::98be:11ff:fe26:d431 Feb 18 12:33:08 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:33:08 volumio77 dhcpcd[812]: vethb254f7a: soliciting a DHCP lease Feb 18 12:33:08 volumio77 dhcpcd[812]: veth3e2c071: soliciting an IPv6 router Feb 18 12:33:08 volumio77 containerd[967]: time="2025-02-18T12:33:08.414988809+08:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 18 12:33:08 volumio77 containerd[967]: time="2025-02-18T12:33:08.416092600+08:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 18 12:33:08 volumio77 containerd[967]: time="2025-02-18T12:33:08.416304258+08:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 18 12:33:08 volumio77 containerd[967]: time="2025-02-18T12:33:08.418043566+08:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e pid=24966 runtime=io.containerd.runc.v2 Feb 18 12:33:08 volumio77 dhcpcd[812]: veth3e2c071: soliciting a DHCP lease Feb 18 12:33:09 volumio77 dhcpcd[812]: vethb254f7a: soliciting an IPv6 router Feb 18 12:33:09 volumio77 dhcpcd[812]: vethb254f7a: carrier lost Feb 18 12:33:09 volumio77 kernel: docker0: port 1(veth3e2c071) entered disabled state Feb 18 12:33:09 volumio77 kernel: eth0: renamed from vethb254f7a Feb 18 12:33:09 volumio77 kernel: docker0: port 1(veth3e2c071) entered blocking state Feb 18 12:33:09 volumio77 kernel: docker0: port 1(veth3e2c071) entered forwarding state Feb 18 12:33:09 volumio77 dhcpcd[812]: vethb254f7a: removing interface Feb 18 12:33:09 volumio77 dhcpcd[812]: veth3e2c071: carrier lost Feb 18 12:33:09 volumio77 dhcpcd[812]: veth3e2c071: carrier acquired Feb 18 12:33:09 volumio77 dhcpcd[812]: veth3e2c071: IAID c8:e7:76:e2 Feb 18 12:33:09 volumio77 dhcpcd[812]: veth3e2c071: soliciting a DHCP lease Feb 18 12:33:10 volumio77 dhcpcd[812]: veth3e2c071: soliciting an IPv6 router Feb 18 12:33:12 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:33:14 volumio77 dhcpcd[812]: veth3e2c071: probing for an IPv4LL address Feb 18 12:33:20 volumio77 dhcpcd[812]: veth3e2c071: using IPv4LL address 169.254.59.164 Feb 18 12:33:20 volumio77 avahi-daemon[700]: Joining mDNS multicast group on interface veth3e2c071.IPv4 with address 169.254.59.164. Feb 18 12:33:20 volumio77 avahi-daemon[700]: New relevant interface veth3e2c071.IPv4 for mDNS. Feb 18 12:33:20 volumio77 avahi-daemon[700]: Registering new address record for 169.254.59.164 on veth3e2c071.IPv4. Feb 18 12:33:20 volumio77 dhcpcd[812]: veth3e2c071: adding route to 169.254.0.0/16 Feb 18 12:33:21 volumio77 volumio[1099]: info: Discovery: adding 2490e78b-ac25-4fc9-aa4c-cb7f5a37b555 Feb 18 12:33:21 volumio77 volumio[1099]: info: Discovery: Found device Volumio77 Feb 18 12:33:21 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:33:21 volumio77 dockerd[1009]: time="2025-02-18T12:33:21.867862255+08:00" level=info msg="ignoring event" container=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Feb 18 12:33:21 volumio77 containerd[967]: time="2025-02-18T12:33:21.867744968+08:00" level=info msg="shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e Feb 18 12:33:21 volumio77 containerd[967]: time="2025-02-18T12:33:21.868645183+08:00" level=warning msg="cleaning up after shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e namespace=moby Feb 18 12:33:21 volumio77 containerd[967]: time="2025-02-18T12:33:21.868971504+08:00" level=info msg="cleaning up dead shim" Feb 18 12:33:21 volumio77 containerd[967]: time="2025-02-18T12:33:21.947237108+08:00" level=warning msg="cleanup warnings time=\"2025-02-18T12:33:21+08:00\" level=info msg=\"starting signal loop\" namespace=moby pid=25147 runtime=io.containerd.runc.v2\n" Feb 18 12:33:22 volumio77 ntpd[1067]: Listen normally on 13 veth3e2c071 169.254.59.164:123 Feb 18 12:33:22 volumio77 ntpd[1067]: new interface(s) found: waking up resolver Feb 18 12:33:22 volumio77 dhcpcd[812]: veth3e2c071: carrier lost Feb 18 12:33:22 volumio77 kernel: docker0: port 1(veth3e2c071) entered disabled state Feb 18 12:33:22 volumio77 kernel: vethb254f7a: renamed from eth0 Feb 18 12:33:22 volumio77 systemd-udevd[25178]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:22 volumio77 systemd-udevd[25178]: Using default interface naming scheme 'v240'. Feb 18 12:33:22 volumio77 avahi-daemon[700]: Interface veth3e2c071.IPv4 no longer relevant for mDNS. Feb 18 12:33:22 volumio77 kernel: docker0: port 1(veth3e2c071) entered disabled state Feb 18 12:33:22 volumio77 avahi-daemon[700]: Leaving mDNS multicast group on interface veth3e2c071.IPv4 with address 169.254.59.164. Feb 18 12:33:22 volumio77 kernel: device veth3e2c071 left promiscuous mode Feb 18 12:33:22 volumio77 kernel: docker0: port 1(veth3e2c071) entered disabled state Feb 18 12:33:22 volumio77 dhcpcd[812]: veth3e2c071: deleting route to 169.254.0.0/16 Feb 18 12:33:22 volumio77 avahi-daemon[700]: Withdrawing address record for 169.254.59.164 on veth3e2c071. Feb 18 12:33:22 volumio77 volumio[1099]: info: Discovery: A device disappeared from network Feb 18 12:33:22 volumio77 volumio[1099]: info: Discovery: Device volumio77 disappeared from network Feb 18 12:33:22 volumio77 systemd[1]: run-docker-netns-cd8813f0b6ae.mount: Succeeded. Feb 18 12:33:22 volumio77 systemd-udevd[25178]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:22 volumio77 systemd-udevd[25178]: Could not generate persistent MAC address for vethb3bca34: No such file or directory Feb 18 12:33:22 volumio77 systemd-udevd[25185]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:22 volumio77 systemd-udevd[25185]: Using default interface naming scheme 'v240'. Feb 18 12:33:22 volumio77 systemd-udevd[25185]: Could not generate persistent MAC address for vethd4d5fc2: No such file or directory Feb 18 12:33:22 volumio77 dhcpcd[812]: veth3e2c071: removing interface Feb 18 12:33:22 volumio77 kernel: docker0: port 1(vethb3bca34) entered blocking state Feb 18 12:33:22 volumio77 kernel: docker0: port 1(vethb3bca34) entered disabled state Feb 18 12:33:22 volumio77 kernel: device vethb3bca34 entered promiscuous mode Feb 18 12:33:22 volumio77 kernel: docker0: port 1(vethb3bca34) entered blocking state Feb 18 12:33:22 volumio77 kernel: docker0: port 1(vethb3bca34) entered forwarding state Feb 18 12:33:22 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:33:22 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:33:22 volumio77 volumio[1099]: info: Listing playlists Feb 18 12:33:22 volumio77 dhcpcd[812]: vethd4d5fc2: waiting for carrier Feb 18 12:33:22 volumio77 dhcpcd[812]: vethb3bca34: IAID c9:79:80:7a Feb 18 12:33:22 volumio77 dhcpcd[812]: vethb3bca34: adding address fe80::9458:c9ff:fe79:807a Feb 18 12:33:22 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:33:22 volumio77 dhcpcd[812]: vethb3bca34: carrier lost Feb 18 12:33:22 volumio77 dhcpcd[812]: vethb3bca34: carrier acquired Feb 18 12:33:22 volumio77 dhcpcd[812]: vethb3bca34: IAID c9:79:80:7a Feb 18 12:33:22 volumio77 dhcpcd[812]: vethd4d5fc2: carrier acquired Feb 18 12:33:22 volumio77 dhcpcd[812]: vethd4d5fc2: IAID 7a:b0:2e:bf Feb 18 12:33:22 volumio77 dhcpcd[812]: vethd4d5fc2: adding address fe80::ccca:7aff:feb0:2ebf Feb 18 12:33:22 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:33:22 volumio77 containerd[967]: time="2025-02-18T12:33:22.929951171+08:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 18 12:33:22 volumio77 containerd[967]: time="2025-02-18T12:33:22.937320012+08:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 18 12:33:22 volumio77 containerd[967]: time="2025-02-18T12:33:22.937524004+08:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 18 12:33:22 volumio77 containerd[967]: time="2025-02-18T12:33:22.939262770+08:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e pid=25327 runtime=io.containerd.runc.v2 Feb 18 12:33:23 volumio77 dhcpcd[812]: vethb3bca34: soliciting a DHCP lease Feb 18 12:33:23 volumio77 dhcpcd[812]: vethd4d5fc2: soliciting an IPv6 router Feb 18 12:33:23 volumio77 dhcpcd[812]: vethd4d5fc2: soliciting a DHCP lease Feb 18 12:33:23 volumio77 dhcpcd[812]: vethb3bca34: soliciting an IPv6 router Feb 18 12:33:23 volumio77 dhcpcd[812]: vethd4d5fc2: carrier lost Feb 18 12:33:23 volumio77 kernel: docker0: port 1(vethb3bca34) entered disabled state Feb 18 12:33:23 volumio77 kernel: eth0: renamed from vethd4d5fc2 Feb 18 12:33:23 volumio77 kernel: docker0: port 1(vethb3bca34) entered blocking state Feb 18 12:33:23 volumio77 kernel: docker0: port 1(vethb3bca34) entered forwarding state Feb 18 12:33:24 volumio77 dhcpcd[812]: vethd4d5fc2: removing interface Feb 18 12:33:24 volumio77 dhcpcd[812]: vethb3bca34: carrier lost Feb 18 12:33:24 volumio77 dhcpcd[812]: vethb3bca34: carrier acquired Feb 18 12:33:24 volumio77 dhcpcd[812]: vethb3bca34: IAID c9:79:80:7a Feb 18 12:33:24 volumio77 dhcpcd[812]: vethb3bca34: soliciting an IPv6 router Feb 18 12:33:25 volumio77 dhcpcd[812]: vethb3bca34: soliciting a DHCP lease Feb 18 12:33:26 volumio77 ntpd[1067]: Deleting interface #13 veth3e2c071, 169.254.59.164#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 14 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 11 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 8 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 6 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 1 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 1 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::play index undefined Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fortnight","artist":"Taylor Swift;Post Malone","album":"THE TORTURED POETS DEPARTMENT: THE ANTHOLOGY","uri":"http://192.168.3.24:8201/MediaItems/275.mp3","trackType":"mp3"} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: [1739853207215] ControllerUPNPBrowser::clearAddPlayTrack Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 155ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 154ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 152ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 151ms Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand stop took 30 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::getQueue Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getQueue Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:27 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand clear took 97 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.24:8201/MediaItems/5670.wav" Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:27 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:27 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:27 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:27 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:27 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:27 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:27 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 22ms Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.24:8201/MediaItems/5670.wav" Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 17ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 14ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 10ms Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:27 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand add "http://192.168.3.24:8201/MediaItems/5670.wav" took 5 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:27 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:27 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:27 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 31ms Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand play took 15 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 15ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 15ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 13ms Feb 18 12:33:27 volumio77 volumio[1099]: info: Clearing queue after UPNP request Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 22 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:27 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 11025,S32_LE,2,32 Feb 18 12:33:27 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate from file: 11025 Feb 18 12:33:27 volumio77 volumio[1099]: info: camilladsp stopping service pid 24751... Feb 18 12:33:27 volumio77 volumio[1099]: info: camilladsp service terminated, instance 1 Feb 18 12:33:27 volumio77 volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 18 12:33:27 volumio77 volumio[1099]: info: camilladsp service started and running in background, instance 1 Feb 18 12:33:27 volumio77 volumio[1099]: info: camilladsp spawned new process with pid 25465, instance 1, run: true Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: Feb 18 12:33:27 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 189 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 181 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 179 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 175 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 174 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 27 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 24 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 18 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 15 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: info: sendMpdCommand status took 12 milliseconds Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5670.wav","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/5670.wav","trackType":"wav"} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 2 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 2 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::play index undefined Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:27 volumio77 volumio[1099]: verbose: CURRENT POSITION 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: No code Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 459ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 458ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 483ms Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: [1739853207913] ControllerUPNPBrowser::clearAddPlayTrack Feb 18 12:33:27 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 316ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 314ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 314ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 309ms Feb 18 12:33:27 volumio77 volumio[1099]: info: ------------------------------ 309ms Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::ClearQueue Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::stop Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::clearPlayQueue Feb 18 12:33:27 volumio77 volumio[1099]: info: CorePlayQueue::saveQueue Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:27 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushQueue Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 386 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 380 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 380 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand stop took 100 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 3 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1281,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5670.wav","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/5670.wav","trackType":"wav"} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 3 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 3 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5670.wav","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/5670.wav","trackType":"wav"} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 3 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 484ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 676ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 671ms Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 248 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 246ms Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand clear took 237 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 7ms Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.24:8201/MediaItems/5671.wav" Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 300ms Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: info: camilladsp spawned new process with pid 25518, instance 1, run: true Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:28 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:28 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:28 volumio77 volumio[1099]: error: updateQueue error: null Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 110ms Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.24:8201/MediaItems/5671.wav" Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 59ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 56ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 11ms Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand add "http://192.168.3.24:8201/MediaItems/5671.wav" took 46 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 44ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 43ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 8ms Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:28 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 25ms Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand play took 19 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 18ms Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 25ms Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 11025,S32_LE,2,32 Feb 18 12:33:28 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate from file: 11025 Feb 18 12:33:28 volumio77 volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 41 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 34 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 30 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 28 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 28 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 25 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 18 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 16 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 14 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5671.wav","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/5671.wav","trackType":"wav"} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5671.wav","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/5671.wav","trackType":"wav"} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:28 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5671.wav","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/5671.wav","trackType":"wav"} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:28 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":549,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5671.wav","artist":null,"album":null,"uri":"http://192.168.3.24:8201/MediaItems/5671.wav","trackType":"wav"} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:28 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 237ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 238ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 281ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 275ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 271ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 269ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 231ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 230ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 229ms Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: Feb 18 12:33:28 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 15 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 13 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: info: sendMpdCommand status took 11 milliseconds Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:28 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:33:28 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: No code Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioPushState Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 90ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 88ms Feb 18 12:33:28 volumio77 volumio[1099]: info: ------------------------------ 88ms Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 18 12:33:28 volumio77 volumio[1099]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 18 12:33:28 volumio77 volumio[1099]: info: Starting UPNP Playback Feb 18 12:33:28 volumio77 volumio[1099]: info: Preparing playback through UPNP Feb 18 12:33:28 volumio77 volumio[1099]: info: Consume mode Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:33:28 volumio77 volumio[1099]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 11ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 10ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 7ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 5ms Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 11ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 9ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 8ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 6ms Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 13ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 10ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 8ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 6ms Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 14ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 12ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 10ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 8ms Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 17ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 12ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 8ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 6ms Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: Feb 18 12:33:29 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:29 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 9ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 7ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 7ms Feb 18 12:33:29 volumio77 volumio[1099]: info: ------------------------------ 5ms Feb 18 12:33:29 volumio77 volumio[1099]: info: camilladsp spawned new process with pid 25542, instance 1, run: true Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Feb 18 12:33:30 volumio77 volumio[1099]: info: FusionDsp - ---- read samplerate from file: 96000 Feb 18 12:33:30 volumio77 volumio[1099]: info: camilladsp stopping service pid 25542... Feb 18 12:33:30 volumio77 dhcpcd[812]: vethb3bca34: probing for an IPv4LL address Feb 18 12:33:30 volumio77 volumio[1099]: info: camilladsp service terminated, instance 1 Feb 18 12:33:30 volumio77 volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 18 12:33:30 volumio77 volumio[1099]: info: camilladsp service started and running in background, instance 1 Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:30 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 102 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 97 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 95 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 85 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 31 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 36ms Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 184ms Feb 18 12:33:30 volumio77 volumio[1099]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 182ms Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 179ms Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 169ms Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:30 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: camilladsp spawned new process with pid 25556, instance 1, run: true Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 111 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 108 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 114 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 107 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 100 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 57ms Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 56 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2875 Kbps","isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 229ms Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:30 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces system playlist update Feb 18 12:33:30 volumio77 volumio[1099]: info: Ignoring MPD Status Update Feb 18 12:33:30 volumio77 volumio[1099]: info: Feb 18 12:33:30 volumio77 volumio[1099]: ---------------------------- MPD announces state update: player Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::getState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 105 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 100 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 99 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 97 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 94 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 49ms Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 38 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 25ms Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand status took 13 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2875 Kbps","isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2875 Kbps","isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2875 Kbps","isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2875 Kbps","isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2875 Kbps","isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:30 volumio77 volumio[1099]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:30 volumio77 volumio[1099]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:30 volumio77 volumio[1099]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:30 volumio77 volumio[1099]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 304ms Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 300ms Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 297ms Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 290ms Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 233ms Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1364,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3323 Kbps","isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: ControllerMpd::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::servicePushState Feb 18 12:33:30 volumio77 volumio[1099]: verbose: In UPNP mode Feb 18 12:33:30 volumio77 volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1490,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3025 Kbps","isStreaming":false,"title":"4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","artist":"Music Assistant","album":null,"uri":"http://192.168.3.143:8097/flow/uuid:45f700b1-2bf7-2c9f-aa7a-0281ef92e450/4c4473c9ad4a4067afc421526b5f2c2b.flac?ts=1739853206","trackType":""} Feb 18 12:33:30 volumio77 volumio[1099]: verbose: CURRENT POSITION 0 Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState stateService play Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:33:30 volumio77 volumio[1099]: info: Received an update from plugin. extracting info from payload Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 12:33:30 volumio77 volumio[1099]: info: CoreStateMachine::pushState Feb 18 12:33:30 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:30 volumio77 volumio[1099]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 112ms Feb 18 12:33:30 volumio77 volumio[1099]: info: ------------------------------ 81ms Feb 18 12:33:32 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:33:32 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:32 volumio77 volumio[1099]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:32 volumio77 volumio[1099]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 18 12:33:32 volumio77 volumio[1099]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Feb 18 12:33:32 volumio77 volumio[1099]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 18 12:33:32 volumio77 volumio[1099]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 18 12:33:32 volumio77 volumio[1099]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 18 12:33:32 volumio77 volumio[1099]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 18 12:33:32 volumio77 volumio[1099]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 18 12:33:32 volumio77 volumio[1099]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 18 12:33:32 volumio77 volumio[1099]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 18 12:33:32 volumio77 volumio[1099]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 18 12:33:32 volumio77 volumio[1099]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 18 12:33:32 volumio77 volumio[1099]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 18 12:33:32 volumio77 volumio[1099]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 18 12:33:32 volumio77 volumio[1099]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 18 12:33:32 volumio77 volumio[1099]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 18 12:33:32 volumio77 volumio[1099]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 18 12:33:35 volumio77 dhcpcd[812]: vethb3bca34: using IPv4LL address 169.254.151.195 Feb 18 12:33:35 volumio77 dhcpcd[812]: vethb3bca34: adding route to 169.254.0.0/16 Feb 18 12:33:35 volumio77 avahi-daemon[700]: Joining mDNS multicast group on interface vethb3bca34.IPv4 with address 169.254.151.195. Feb 18 12:33:35 volumio77 avahi-daemon[700]: New relevant interface vethb3bca34.IPv4 for mDNS. Feb 18 12:33:35 volumio77 avahi-daemon[700]: Registering new address record for 169.254.151.195 on vethb3bca34.IPv4. Feb 18 12:33:36 volumio77 volumio[1099]: info: Discovery: adding 2490e78b-ac25-4fc9-aa4c-cb7f5a37b555 Feb 18 12:33:36 volumio77 volumio[1099]: info: Discovery: Found device Volumio77 Feb 18 12:33:36 volumio77 volumio[1099]: info: CoreCommandRouter::volumioGetState Feb 18 12:33:36 volumio77 volumio[1099]: info: CorePlayQueue::getTrack 0 Feb 18 12:33:36 volumio77 volumio[1099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 12:33:36 volumio77 volumio[1099]: TypeError: Cannot read property 'name' of undefined Feb 18 12:33:36 volumio77 volumio[1099]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 18 12:33:36 volumio77 volumio[1099]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Feb 18 12:33:36 volumio77 volumio[1099]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:272:40) Feb 18 12:33:36 volumio77 volumio[1099]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Feb 18 12:33:36 volumio77 volumio[1099]: at Browser.emit (events.js:400:28) Feb 18 12:33:36 volumio77 volumio[1099]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Feb 18 12:33:36 volumio77 volumio[1099]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Feb 18 12:33:36 volumio77 volumio[1099]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Feb 18 12:33:36 volumio77 volumio[1099]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Feb 18 12:33:36 volumio77 volumio[1099]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Feb 18 12:33:36 volumio77 volumio[1099]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Feb 18 12:33:36 volumio77 volumio[1099]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Feb 18 12:33:36 volumio77 volumio[1099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 12:33:37 volumio77 ntpd[1067]: Listen normally on 14 vethb3bca34 169.254.151.195:123 Feb 18 12:33:37 volumio77 ntpd[1067]: new interface(s) found: waking up resolver Feb 18 12:33:37 volumio77 dockerd[1009]: time="2025-02-18T12:33:37.525420552+08:00" level=info msg="ignoring event" container=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Feb 18 12:33:37 volumio77 containerd[967]: time="2025-02-18T12:33:37.532661938+08:00" level=info msg="shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e Feb 18 12:33:37 volumio77 containerd[967]: time="2025-02-18T12:33:37.533296872+08:00" level=warning msg="cleaning up after shim disconnected" id=d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e namespace=moby Feb 18 12:33:37 volumio77 containerd[967]: time="2025-02-18T12:33:37.533485032+08:00" level=info msg="cleaning up dead shim" Feb 18 12:33:37 volumio77 containerd[967]: time="2025-02-18T12:33:37.600339865+08:00" level=warning msg="cleanup warnings time=\"2025-02-18T12:33:37+08:00\" level=info msg=\"starting signal loop\" namespace=moby pid=25625 runtime=io.containerd.runc.v2\n" Feb 18 12:33:37 volumio77 dhcpcd[812]: vethb3bca34: carrier lost Feb 18 12:33:37 volumio77 kernel: docker0: port 1(vethb3bca34) entered disabled state Feb 18 12:33:37 volumio77 kernel: vethd4d5fc2: renamed from eth0 Feb 18 12:33:37 volumio77 systemd-udevd[25658]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:37 volumio77 systemd-udevd[25658]: Using default interface naming scheme 'v240'. Feb 18 12:33:37 volumio77 avahi-daemon[700]: Interface vethb3bca34.IPv4 no longer relevant for mDNS. Feb 18 12:33:37 volumio77 avahi-daemon[700]: Leaving mDNS multicast group on interface vethb3bca34.IPv4 with address 169.254.151.195. Feb 18 12:33:37 volumio77 kernel: docker0: port 1(vethb3bca34) entered disabled state Feb 18 12:33:37 volumio77 kernel: device vethb3bca34 left promiscuous mode Feb 18 12:33:37 volumio77 kernel: docker0: port 1(vethb3bca34) entered disabled state Feb 18 12:33:37 volumio77 avahi-daemon[700]: Withdrawing address record for 169.254.151.195 on vethb3bca34. Feb 18 12:33:37 volumio77 dhcpcd[812]: vethb3bca34: deleting route to 169.254.0.0/16 Feb 18 12:33:38 volumio77 systemd[1]: run-docker-netns-4a1739e5d2df.mount: Succeeded. Feb 18 12:33:38 volumio77 systemd-udevd[25658]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:38 volumio77 systemd-udevd[25658]: Could not generate persistent MAC address for vethfa5e9ae: No such file or directory Feb 18 12:33:38 volumio77 systemd-udevd[25665]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 18 12:33:38 volumio77 systemd-udevd[25665]: Using default interface naming scheme 'v240'. Feb 18 12:33:38 volumio77 systemd-udevd[25665]: Could not generate persistent MAC address for vethaa0c53a: No such file or directory Feb 18 12:33:38 volumio77 kernel: docker0: port 1(vethaa0c53a) entered blocking state Feb 18 12:33:38 volumio77 kernel: docker0: port 1(vethaa0c53a) entered disabled state Feb 18 12:33:38 volumio77 kernel: device vethaa0c53a entered promiscuous mode Feb 18 12:33:38 volumio77 kernel: docker0: port 1(vethaa0c53a) entered blocking state Feb 18 12:33:38 volumio77 kernel: docker0: port 1(vethaa0c53a) entered forwarding state Feb 18 12:33:38 volumio77 dhcpcd[812]: vethb3bca34: removing interface Feb 18 12:33:38 volumio77 dhcpcd[812]: vethfa5e9ae: waiting for carrier Feb 18 12:33:38 volumio77 dhcpcd[812]: vethaa0c53a: IAID 2b:d1:66:6a Feb 18 12:33:38 volumio77 dhcpcd[812]: vethaa0c53a: adding address fe80::90dc:2bff:fed1:666a Feb 18 12:33:38 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:33:38 volumio77 dhcpcd[812]: vethaa0c53a: carrier lost Feb 18 12:33:38 volumio77 dhcpcd[812]: vethaa0c53a: carrier acquired Feb 18 12:33:38 volumio77 dhcpcd[812]: vethaa0c53a: IAID 2b:d1:66:6a Feb 18 12:33:38 volumio77 dhcpcd[812]: vethfa5e9ae: carrier acquired Feb 18 12:33:38 volumio77 dhcpcd[812]: vethfa5e9ae: IAID ea:12:4e:a6 Feb 18 12:33:38 volumio77 dhcpcd[812]: vethfa5e9ae: adding address fe80::8860:eaff:fe12:4ea6 Feb 18 12:33:38 volumio77 dhcpcd[812]: ipv6_addaddr1: Permission denied Feb 18 12:33:38 volumio77 sudo[25751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-18 12:32 Feb 18 12:33:38 volumio77 sudo[25751]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 12:33:38 volumio77 containerd[967]: time="2025-02-18T12:33:38.790167307+08:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 18 12:33:38 volumio77 containerd[967]: time="2025-02-18T12:33:38.791208017+08:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 18 12:33:38 volumio77 containerd[967]: time="2025-02-18T12:33:38.791407884+08:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 18 12:33:38 volumio77 containerd[967]: time="2025-02-18T12:33:38.793309227+08:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e pid=25800 runtime=io.containerd.runc.v2 Feb 18 12:33:38 volumio77 systemd[1]: run-docker-runtime\x2drunc-moby-d896deafbb925593b3ef764514e8eff416e0ea644e8c1eec40d24b78ce540a4e-runc.xnkCnb.mount: Succeeded. Feb 18 12:33:39 volumio77 dhcpcd[812]: vethaa0c53a: soliciting an IPv6 router 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Sep 2024 10:15:17 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="49ee0b71bdee95489f79b31a5db4cbdb"