-- Logs begin at Mon 2025-09-15 21:01:15 CEST, end at Mon 2025-09-15 21:06:01 CEST. -- Sep 15 21:05:00 linus-volumio wpa_supplicant[903]: wlan0: Trying to associate with SSID 'Default1' Sep 15 21:05:00 linus-volumio dhcpcd[915]: wlan0: carrier acquired Sep 15 21:05:00 linus-volumio dhcpcd[915]: wlan0: IAID 01:0a:90:09 Sep 15 21:05:00 linus-volumio dhcpcd[915]: wlan0: carrier lost Sep 15 21:05:00 linus-volumio wpa_supplicant[903]: wlan0: Associated with 5c:62:8b:ba:95:86 Sep 15 21:05:00 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:62:8b:ba:95:86 completed [id=0 id_str=] Sep 15 21:05:00 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 15 21:05:00 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 15 21:05:00 linus-volumio dhcpcd[915]: wlan0: carrier acquired Sep 15 21:05:00 linus-volumio dhcpcd[915]: wlan0: IAID 01:0a:90:09 Sep 15 21:05:00 linus-volumio dhcpcd[915]: wlan0: soliciting an IPv6 router Sep 15 21:05:00 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-DISCONNECTED bssid=5c:62:8b:ba:95:86 reason=1 Sep 15 21:05:00 linus-volumio dhcpcd[915]: wlan0: carrier lost Sep 15 21:05:00 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 15 21:05:00 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CH Sep 15 21:05:02 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:02+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Sep 15 21:05:02 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:02+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Sep 15 21:05:02 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:02+02:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Sep 15 21:05:02 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:02+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Sep 15 21:05:02 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:02+02:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Sep 15 21:05:03 linus-volumio ntpd[800]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 15 21:05:04 linus-volumio wpa_supplicant[903]: wlan0: Trying to associate with SSID 'Default1' Sep 15 21:05:04 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 15 21:05:07 linus-volumio ntpd[800]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 15 21:05:07 linus-volumio ntpd[800]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 15 21:05:07 linus-volumio ntpd[800]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 15 21:05:08 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:08+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Sep 15 21:05:08 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:08+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Sep 15 21:05:08 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:08+02:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Sep 15 21:05:08 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:08+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Sep 15 21:05:08 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:08+02:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Sep 15 21:05:11 linus-volumio wpa_supplicant[903]: wlan0: Trying to associate with SSID 'Default1' Sep 15 21:05:11 linus-volumio dhcpcd[915]: wlan0: carrier acquired Sep 15 21:05:11 linus-volumio wpa_supplicant[903]: wlan0: Associated with 5c:62:8b:ba:95:86 Sep 15 21:05:11 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:62:8b:ba:95:86 completed [id=0 id_str=] Sep 15 21:05:11 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 15 21:05:11 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 15 21:05:11 linus-volumio dhcpcd[915]: wlan0: IAID 01:0a:90:09 Sep 15 21:05:11 linus-volumio dhcpcd[915]: wlan0: carrier lost Sep 15 21:05:11 linus-volumio dhcpcd[915]: wlan0: carrier acquired Sep 15 21:05:11 linus-volumio dhcpcd[915]: wlan0: IAID 01:0a:90:09 Sep 15 21:05:11 linus-volumio dhcpcd[915]: wlan0: soliciting a DHCP lease Sep 15 21:05:12 linus-volumio dhcpcd[915]: wlan0: soliciting an IPv6 router Sep 15 21:05:12 linus-volumio dhcpcd[915]: wlan0: offered 192.168.1.5 from 192.168.1.23 Sep 15 21:05:12 linus-volumio dhcpcd[915]: wlan0: probing address 192.168.1.5/24 Sep 15 21:05:17 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:17+02:00" level=error msg="did not receive last pong from dealer, 150s passed" Sep 15 21:05:17 linus-volumio dhcpcd[915]: wlan0: leased 192.168.1.5 for 1800 seconds Sep 15 21:05:17 linus-volumio avahi-daemon[647]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.5. Sep 15 21:05:17 linus-volumio dhcpcd[915]: wlan0: adding route to 192.168.1.0/24 Sep 15 21:05:17 linus-volumio dhcpcd[915]: wlan0: adding default route via 192.168.1.1 Sep 15 21:05:17 linus-volumio avahi-daemon[647]: New relevant interface wlan0.IPv4 for mDNS. Sep 15 21:05:17 linus-volumio avahi-daemon[647]: Registering new address record for 192.168.1.5 on wlan0.IPv4. Sep 15 21:05:18 linus-volumio volumio[1004]: info: Discovery: adding 367a13f8-54e6-4dbf-962a-1844b8070dd1 Sep 15 21:05:18 linus-volumio volumio[1004]: info: Discovery: Found device linus-volumio Sep 15 21:05:18 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 15 21:05:18 linus-volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="completed keyexchange" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="completed challenge" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=info msg="authenticated AP" username="tu*******hd" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="re-established accesspoint connection" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=trace msg="received accesspoint ping" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 15 21:05:18 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:18+02:00" level=trace msg="received accesspoint pong ack" Sep 15 21:05:19 linus-volumio ntpd[800]: Listen normally on 4 wlan0 192.168.1.5:123 Sep 15 21:05:19 linus-volumio ntpd[800]: new interface(s) found: waking up resolver Sep 15 21:05:19 linus-volumio volumio[1004]: info: Volumio Network Manager: Network status updated: 2 Sep 15 21:05:45 linus-volumio volumio[1004]: info: Clearing queue after UPNP request Sep 15 21:05:45 linus-volumio volumio[1004]: info: CoreStateMachine::ClearQueue Sep 15 21:05:45 linus-volumio volumio[1004]: info: CoreStateMachine::stop Sep 15 21:05:45 linus-volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 21:05:45 linus-volumio volumio[1004]: info: CorePlayQueue::clearPlayQueue Sep 15 21:05:45 linus-volumio volumio[1004]: info: CorePlayQueue::saveQueue Sep 15 21:05:45 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:45 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Sep 15 21:05:45 linus-volumio volumio[1004]: error: Upnp client error: Error: This socket has been ended by the other party Sep 15 21:05:45 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:46 linus-volumio volumio-remote-updater[636]: No test mode Sep 15 21:05:46 linus-volumio volumio-remote-updater[636]: No alpha test mode Sep 15 21:05:46 linus-volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 15 21:05:46 linus-volumio volumio[1004]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 15 21:05:46 linus-volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 15 21:05:46 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:46+02:00" level=debug msg="dealer connection opened" Sep 15 21:05:46 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:46+02:00" level=debug msg="re-established dealer connection" Sep 15 21:05:46 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:46+02:00" level=debug msg="received connection id: MjVjZjc3MDctYjg5...MUEyQzcxOUVEQg==" Sep 15 21:05:46 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:46+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 15 21:05:47 linus-volumio volumio[1004]: info: Starting UPNP Playback Sep 15 21:05:47 linus-volumio volumio[1004]: info: Preparing playback through UPNP Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 15 21:05:47 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:47+02:00" level=trace msg="sent dealer ping" Sep 15 21:05:47 linus-volumio go-librespot[1205]: time="2025-09-15T21:05:47+02:00" level=trace msg="received dealer pong" Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 6ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 5ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 4ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 4ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 3ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 2ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 3ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 3ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 2ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 3ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 2ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 1ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 3ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 2ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 1ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:47 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 3ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 2ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 2ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand status took 4 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand status took 6 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand status took 6 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand status took 3 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 18ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:47 linus-volumio volumio[1004]: info: Sep 15 21:05:47 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand status took 3 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand status took 3 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Sep 15 21:05:47 linus-volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Sep 15 21:05:47 linus-volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Sep 15 21:05:47 linus-volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 53ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 53ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 51ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 34 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 34 milliseconds Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Sep 15 21:05:47 linus-volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:47 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Sep 15 21:05:47 linus-volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 59ms Sep 15 21:05:47 linus-volumio volumio[1004]: info: ------------------------------ 58ms Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:47 linus-volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 15 21:05:48 linus-volumio volumio[1004]: info: Sep 15 21:05:48 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:48 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:48 linus-volumio volumio[1004]: info: Sep 15 21:05:48 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:48 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:48 linus-volumio volumio[1004]: info: Sep 15 21:05:48 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:48 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:48 linus-volumio volumio[1004]: info: Sep 15 21:05:48 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:48 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:48 linus-volumio volumio[1004]: info: Sep 15 21:05:48 linus-volumio volumio[1004]: ---------------------------- MPD announces system playlist update Sep 15 21:05:48 linus-volumio volumio[1004]: info: Ignoring MPD Status Update Sep 15 21:05:48 linus-volumio volumio[1004]: info: Sep 15 21:05:48 linus-volumio volumio[1004]: ---------------------------- MPD announces state update: player Sep 15 21:05:48 linus-volumio volumio[1004]: info: ControllerMpd::getState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:05:48 linus-volumio volumio[1004]: info: ------------------------------ 6ms Sep 15 21:05:48 linus-volumio volumio[1004]: info: sendMpdCommand status took 4 milliseconds Sep 15 21:05:48 linus-volumio volumio[1004]: info: ------------------------------ 4ms Sep 15 21:05:48 linus-volumio volumio[1004]: info: sendMpdCommand status took 4 milliseconds Sep 15 21:05:48 linus-volumio volumio[1004]: info: ------------------------------ 3ms Sep 15 21:05:48 linus-volumio volumio[1004]: info: sendMpdCommand status took 2 milliseconds Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::parseState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:05:48 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 15 21:05:48 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 21:05:48 linus-volumio volumio[1004]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:05:48 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh.raw","artist":"swyh-rs","album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Sep 15 21:05:48 linus-volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:48 linus-volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 15 21:05:48 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh.raw","artist":"swyh-rs","album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Sep 15 21:05:48 linus-volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:48 linus-volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 15 21:05:48 linus-volumio volumio[1004]: info: ControllerMpd::pushState Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreCommandRouter::servicePushState Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: In UPNP mode Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh.raw","artist":"swyh-rs","album":null,"uri":"http://192.168.1.98:5901/stream/swyh.raw","trackType":"raw"} Sep 15 21:05:48 linus-volumio volumio[1004]: verbose: CURRENT POSITION 0 Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Sep 15 21:05:48 linus-volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 15 21:05:48 linus-volumio volumio[1004]: info: CoreStateMachine::pushState Sep 15 21:05:48 linus-volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 15 21:05:48 linus-volumio volumio[1004]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 15 21:05:48 linus-volumio volumio[1004]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 15 21:05:48 linus-volumio volumio[1004]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 15 21:05:48 linus-volumio volumio[1004]: info: ------------------------------ 18ms Sep 15 21:05:48 linus-volumio volumio[1004]: info: ------------------------------ 16ms Sep 15 21:05:48 linus-volumio volumio[1004]: info: ------------------------------ 15ms Sep 15 21:05:53 linus-volumio wpa_supplicant[903]: RRM: Ignoring radio measurement request: Not RRM network Sep 15 21:05:53 linus-volumio dhcpcd[915]: wlan0: carrier lost Sep 15 21:05:53 linus-volumio wpa_supplicant[903]: wlan0: Associated with 5c:62:8b:ba:95:87 Sep 15 21:05:53 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:62:8b:ba:95:87 completed [id=0 id_str=] Sep 15 21:05:53 linus-volumio wpa_supplicant[903]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 15 21:05:53 linus-volumio avahi-daemon[647]: Withdrawing address record for 192.168.1.5 on wlan0. Sep 15 21:05:53 linus-volumio avahi-daemon[647]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.5. Sep 15 21:05:53 linus-volumio dhcpcd[915]: wlan0: deleting route to 192.168.1.0/24 Sep 15 21:05:53 linus-volumio dhcpcd[915]: wlan0: deleting default route via 192.168.1.1 Sep 15 21:05:53 linus-volumio avahi-daemon[647]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 15 21:05:53 linus-volumio volumio[1004]: info: Discovery: A device disappeared from network Sep 15 21:05:53 linus-volumio volumio[1004]: info: Discovery: Device linus-volumio disappeared from network Sep 15 21:05:53 linus-volumio dhcpcd[915]: wlan0: carrier acquired Sep 15 21:05:53 linus-volumio dhcpcd[915]: wlan0: IAID 01:0a:90:09 Sep 15 21:05:53 linus-volumio dhcpcd[915]: wlan0: soliciting an IPv6 router Sep 15 21:05:54 linus-volumio dhcpcd[915]: wlan0: rebinding lease of 192.168.1.5 Sep 15 21:05:54 linus-volumio dhcpcd[915]: wlan0: probing address 192.168.1.5/24 Sep 15 21:05:55 linus-volumio ntpd[800]: Deleting interface #4 wlan0, 192.168.1.5#123, interface stats: received=0, sent=0, dropped=0, active_time=36 secs Sep 15 21:05:59 linus-volumio volumio[1004]: info: Volumio Network Manager: Network status updated: 0 Sep 15 21:05:59 linus-volumio dhcpcd[915]: wlan0: leased 192.168.1.5 for 1800 seconds Sep 15 21:05:59 linus-volumio avahi-daemon[647]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.5. Sep 15 21:05:59 linus-volumio avahi-daemon[647]: New relevant interface wlan0.IPv4 for mDNS. Sep 15 21:05:59 linus-volumio avahi-daemon[647]: Registering new address record for 192.168.1.5 on wlan0.IPv4. Sep 15 21:05:59 linus-volumio dhcpcd[915]: wlan0: adding route to 192.168.1.0/24 Sep 15 21:05:59 linus-volumio dhcpcd[915]: wlan0: adding default route via 192.168.1.1 Sep 15 21:06:00 linus-volumio volumio[1004]: info: Discovery: adding 367a13f8-54e6-4dbf-962a-1844b8070dd1 Sep 15 21:06:00 linus-volumio volumio[1004]: info: Discovery: Found device linus-volumio Sep 15 21:06:00 linus-volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 15 21:06:00 linus-volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 15 21:06:00 linus-volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 21:06:00 linus-volumio volumio[1004]: TypeError: Cannot read property 'name' of undefined Sep 15 21:06:00 linus-volumio volumio[1004]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 15 21:06:00 linus-volumio volumio[1004]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 15 21:06:00 linus-volumio volumio[1004]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:272:40) Sep 15 21:06:00 linus-volumio volumio[1004]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Sep 15 21:06:00 linus-volumio volumio[1004]: at Browser.emit (events.js:315:20) Sep 15 21:06:00 linus-volumio volumio[1004]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Sep 15 21:06:00 linus-volumio volumio[1004]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Sep 15 21:06:00 linus-volumio volumio[1004]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Sep 15 21:06:00 linus-volumio volumio[1004]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Sep 15 21:06:00 linus-volumio volumio[1004]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Sep 15 21:06:00 linus-volumio volumio[1004]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Sep 15 21:06:00 linus-volumio volumio[1004]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Sep 15 21:06:00 linus-volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 21:06:01 linus-volumio ntpd[800]: Listen normally on 5 wlan0 192.168.1.5:123 Sep 15 21:06:01 linus-volumio ntpd[800]: new interface(s) found: waking up resolver Sep 15 21:06:01 linus-volumio sudo[2051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 21:05 Sep 15 21:06:01 linus-volumio sudo[2051]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"