-- Logs begin at Wed 2024-07-10 14:39:56 CEST, end at Mon 2024-07-22 12:29:03 CEST. -- Jul 22 12:28:00 volumioz volumio[802]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Jul 22 12:28:00 volumioz sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::volumioGetState Jul 22 12:28:00 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:00 volumioz systemd[1]: Stopped Volspotconnect2 Daemon. Jul 22 12:28:00 volumioz sudo[1025]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 22 12:28:00 volumioz sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:00 volumioz systemd[1]: Started Volspotconnect2 Daemon. Jul 22 12:28:00 volumioz sudo[1025]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:00 volumioz sudo[1027]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:00 volumioz volumio[802]: info: Received Get System Version Jul 22 12:28:00 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 22 12:28:00 volumioz volumio[802]: [SpotifyConnect] null Jul 22 12:28:00 volumioz volumio[1033]: vollibrespot v0.1.9 c98fd5d 2019-11-08 (librespot 4674946 2019-11-08) -- Built On 2019-11-08 Jul 22 12:28:00 volumioz volumio[1033]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: ParseIntError { kind: InvalidDigit }', src/libcore/result.rs:1084:5 Jul 22 12:28:00 volumioz volumio[1033]: stack backtrace: Jul 22 12:28:01 volumioz volumio[802]: [SpotifyConnect] Restarting Vollibrespot Daemon Jul 22 12:28:01 volumioz sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 22 12:28:01 volumioz sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:01 volumioz volumio[802]: info: Received Get System Info Jul 22 12:28:01 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 12:28:01 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 12:28:01 volumioz volumio[802]: info: Discovery: Getting this device information Jul 22 12:28:01 volumioz volumio[802]: info: CoreCommandRouter::volumioGetState Jul 22 12:28:01 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:28:01 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 12:28:01 volumioz sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Jul 22 12:28:01 volumioz sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:01 volumioz volumio[1033]: 0: 0xa7fdf8 - backtrace::backtrace::libunwind::trace::h89fcc71e59e3bc5b Jul 22 12:28:01 volumioz volumio[1033]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.34/src/backtrace/libunwind.rs:88 Jul 22 12:28:01 volumioz volumio[1033]: 1: 0xa7fdf8 - backtrace::backtrace::trace_unsynchronized::h244e30b4d61a86e0 Jul 22 12:28:01 volumioz volumio[1033]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.34/src/backtrace/mod.rs:66 Jul 22 12:28:01 volumioz volumio[1033]: 2: 0xa7fdf8 - std::sys_common::backtrace::_print::h978df54e55c6209d Jul 22 12:28:01 volumioz volumio[1033]: at src/libstd/sys_common/backtrace.rs:47 Jul 22 12:28:01 volumioz volumio[1033]: 3: 0xa7fdf8 - std::sys_common::backtrace::print::ha75ae47f14b8f7f3 Jul 22 12:28:01 volumioz volumio[1033]: at src/libstd/sys_common/backtrace.rs:36 Jul 22 12:28:01 volumioz volumio[1033]: 4: 0xa7fdf8 - std::panicking::default_hook::{{closure}}::h004e1d529e652ede Jul 22 12:28:01 volumioz volumio[1033]: at src/libstd/panicking.rs:200 Jul 22 12:28:01 volumioz volumio[1033]: 5: 0xa7f9dc - std::panicking::default_hook::h185ef7ff1eea4221 Jul 22 12:28:01 volumioz volumio[1033]: at src/libstd/panicking.rs:214 Jul 22 12:28:01 volumioz volumio[1033]: 6: 0xa805b4 - std::panicking::rust_panic_with_hook::hfe0a86ed15580d4a Jul 22 12:28:01 volumioz volumio[1033]: at src/libstd/panicking.rs:477 Jul 22 12:28:01 volumioz volumio[1033]: 7: 0xa80180 - std::panicking::continue_panic_fmt::h182e68ecbb1c932b Jul 22 12:28:01 volumioz volumio[1033]: at src/libstd/panicking.rs:384 Jul 22 12:28:01 volumioz volumio[1033]: 8: 0xa8006c - rust_begin_unwind Jul 22 12:28:01 volumioz volumio[1033]: at src/libstd/panicking.rs:311 Jul 22 12:28:02 volumioz sudo[1039]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:02 volumioz volumio[802]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 22 12:28:02 volumioz volumio[802]: info: CoreStateMachine::getcurrentVolume Jul 22 12:28:02 volumioz volumio[802]: info: CoreCommandRouter::volumioRetrievevolume Jul 22 12:28:02 volumioz systemd[1]: Stopping Volspotconnect2 Daemon... Jul 22 12:28:02 volumioz systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM Jul 22 12:28:02 volumioz volumio[802]: [SpotifyConnect] Vollibrespot Daemon Started Jul 22 12:28:02 volumioz volumio[802]: info: VolumeController:: Volume=0 Mute =false Jul 22 12:28:02 volumioz volumio[802]: info: CoreStateMachine::pushState Jul 22 12:28:02 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:28:02 volumioz volumio[802]: info: CoreCommandRouter::volumioPushState Jul 22 12:28:02 volumioz volumio[802]: info: CoreStateMachine::updateTrackBlock Jul 22 12:28:02 volumioz volumio[802]: info: CorePlayQueue::getTrackBlock Jul 22 12:28:02 volumioz volumio[802]: info: CoreCommandRouter::volumioRetrievevolume Jul 22 12:28:03 volumioz volumio[802]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"01 - MDR Jump","artist":"","album":"","albumart":"/albumart","uri":"http://avw.mdr.de/streams/284320-0_mp3_high.m3u","trackType":"webradio","seek":3690,"samplerate":"","bitdepth":"","channels":0,"random":true,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"webradio","updatedb":false,"volatile":false,"service":"webradio"} connection: {"reconnect":false,"send_delay":5000,"verify_commands":false,"port":"60128","host":"192.168.1.38","model":"TX-8150"} Jul 22 12:28:03 volumioz volumio[802]: info: ONKYO-CONTROL: eiscp connecting... Jul 22 12:28:03 volumioz volumio[802]: info: ONKYO-CONTROL: eiscp connected Jul 22 12:28:03 volumioz volumio[802]: info: ONKYO-CONTROL: eiscp.command('system-power=standby') Jul 22 12:28:03 volumioz volumio[802]: info: VolumeController:: Volume=0 Mute =false Jul 22 12:28:03 volumioz volumio[802]: info: CoreStateMachine::pushState Jul 22 12:28:03 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:28:03 volumioz volumio[802]: info: CoreCommandRouter::volumioPushState Jul 22 12:28:03 volumioz volumio[802]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"01 - MDR Jump","artist":"","album":"","albumart":"/albumart","uri":"http://avw.mdr.de/streams/284320-0_mp3_high.m3u","trackType":"webradio","seek":3690,"samplerate":"","bitdepth":"","channels":0,"random":true,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"webradio","updatedb":false,"volatile":false,"service":"webradio"} connection: {"reconnect":false,"send_delay":5000,"verify_commands":false,"port":"60128","host":"192.168.1.38","model":"TX-8150"} Jul 22 12:28:09 volumioz mpd[1036]: Jul 22 12:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 22 12:28:09 volumioz systemd[1]: Started Music Player Daemon. Jul 22 12:28:09 volumioz sudo[1022]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:09 volumioz volumio[802]: error: updateQueue error: null Jul 22 12:28:19 volumioz volumio[802]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 22 12:28:19 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 22 12:28:19 volumioz volumio[802]: info: CoreCommandRouter::volumioGetState Jul 22 12:28:19 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:28:19 volumioz volumio[802]: info: Restoring Previous Volume level: false false Jul 22 12:28:19 volumioz volumio[802]: info: Volume configurations have been set Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 22 12:28:20 volumioz volumio[802]: info: Updating Volume Controller Parameters: Device: 5 Name: USB PnP Sound Device Mixer: Speaker Max Vol: 50 Vol Curve; logarithmic Vol Steps: 1 Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 22 12:28:20 volumioz volumio[802]: info: Disabling external Volume Control Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 22 12:28:20 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 22 12:28:20 volumioz volumio[802]: [SpotifyConnect] Restarting Vollibrespot Daemon Jul 22 12:28:21 volumioz volumio[802]: info: VolumeController:: Volume=0 Mute =false Jul 22 12:28:21 volumioz sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Jul 22 12:28:21 volumioz volumio[802]: info: CoreStateMachine::pushState Jul 22 12:28:21 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:28:21 volumioz volumio[802]: info: CoreCommandRouter::volumioPushState Jul 22 12:28:21 volumioz sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:21 volumioz volumio[802]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"01 - MDR Jump","artist":"","album":"","albumart":"/albumart","uri":"http://avw.mdr.de/streams/284320-0_mp3_high.m3u","trackType":"webradio","seek":3690,"samplerate":"","bitdepth":"","channels":0,"random":true,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"webradio","updatedb":false,"volatile":false,"service":"webradio"} connection: {"reconnect":false,"send_delay":5000,"verify_commands":false,"port":"60128","host":"192.168.1.38","model":"TX-8150"} Jul 22 12:28:24 volumioz volumio[802]: info: VolumeController::SetAlsaVolumeNaN Jul 22 12:28:24 volumioz volumio[802]: info: CoreStateMachine::pushState Jul 22 12:28:24 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:28:24 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 12:28:24 volumioz volumio[802]: info: CoreCommandRouter::volumioPushState Jul 22 12:28:24 volumioz volumio[802]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"01 - MDR Jump","artist":"","album":"","albumart":"/albumart","uri":"http://avw.mdr.de/streams/284320-0_mp3_high.m3u","trackType":"webradio","seek":3690,"samplerate":"","bitdepth":"","channels":0,"random":true,"repeat":null,"repeatSingle":false,"consume":false,"volume":null,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"webradio","updatedb":false,"volatile":false,"service":"webradio"} connection: {"reconnect":false,"send_delay":5000,"verify_commands":false,"port":"60128","host":"192.168.1.38","model":"TX-8150"} Jul 22 12:28:29 volumioz volumio[802]: info: CoreCommandRouter::volumioGetState Jul 22 12:28:29 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:28:32 volumioz volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 22 12:28:32 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 22 12:28:32 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 22 12:28:32 volumioz sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 22 12:28:32 volumioz sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:32 volumioz sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 22 12:28:32 volumioz sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:33 volumioz sudo[1110]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:33 volumioz sudo[1105]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:33 volumioz sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 22 12:28:33 volumioz sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:33 volumioz sudo[1117]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:33 volumioz sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 22 12:28:33 volumioz sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:33 volumioz sudo[1129]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:33 volumioz sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 12:28:33 volumioz sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:33 volumioz sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 12:28:33 volumioz sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:33 volumioz sudo[1140]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:33 volumioz sudo[1143]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:33 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 22 12:28:33 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 22 12:28:34 volumioz sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 22 12:28:34 volumioz sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:35 volumioz sudo[1149]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:43 volumioz volumio[802]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] Jul 22 12:28:43 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings Jul 22 12:28:43 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 22 12:28:43 volumioz volumio[802]: info: Discovery: Restarting Advertising due to device name change Jul 22 12:28:43 volumioz sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /etc/resolv.conf.tail.tmpl /etc/resolv.conf.tail Jul 22 12:28:43 volumioz sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:43 volumioz sudo[1152]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:43 volumioz sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 22 12:28:43 volumioz sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:43 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 22 12:28:43 volumioz volumio[802]: info: Discovery: Restarting Advertising due to device name change Jul 22 12:28:43 volumioz sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Jul 22 12:28:43 volumioz systemd[1]: wireless.service: Killing process 732 (wpa_supplicant) with signal SIGKILL. Jul 22 12:28:43 volumioz systemd[1]: wireless.service: Killing process 753 (dhcpcd) with signal SIGKILL. Jul 22 12:28:43 volumioz sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:43 volumioz systemd[1]: Stopping Wireless Services... Jul 22 12:28:43 volumioz systemd[1]: wireless.service: Succeeded. Jul 22 12:28:43 volumioz systemd[1]: Stopped Wireless Services. Jul 22 12:28:44 volumioz systemd[1]: Starting Wireless Services... Jul 22 12:28:44 volumioz sudo[1157]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:44 volumioz volumio[802]: info: Discovery: A device disappeared from network Jul 22 12:28:44 volumioz volumio[802]: info: Discovery: Device volumioz disappeared from network Jul 22 12:28:46 volumioz wireless.js[1161]: Cleaning previous... Jul 22 12:28:47 volumioz sudo[1184]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 22 12:28:47 volumioz sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:47 volumioz avahi-daemon[381]: Withdrawing address record for 192.168.1.45 on wlan0. Jul 22 12:28:47 volumioz avahi-daemon[381]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.45. Jul 22 12:28:47 volumioz avahi-daemon[381]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 22 12:28:47 volumioz sudo[1184]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:47 volumioz sudo[1186]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 22 12:28:47 volumioz sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:47 volumioz sudo[1186]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:47 volumioz sudo[1041]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:47 volumioz sudo[1098]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:47 volumioz volumio[802]: [SpotifyConnect] Error: Command failed: /usr/bin/sudo /bin/systemctl restart volspotconnect2.service Jul 22 12:28:47 volumioz volumio[802]: Terminated Jul 22 12:28:47 volumioz volumio[802]: at ChildProcess.exithandler (child_process.js:308:12) Jul 22 12:28:47 volumioz volumio[802]: at ChildProcess.emit (events.js:315:20) Jul 22 12:28:47 volumioz volumio[802]: at maybeClose (internal/child_process.js:1048:16) Jul 22 12:28:47 volumioz volumio[802]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Jul 22 12:28:48 volumioz volumio[802]: [SpotifyConnect] Error: Command failed: /usr/bin/sudo /bin/systemctl restart volspotconnect2.service Jul 22 12:28:48 volumioz volumio[802]: Terminated Jul 22 12:28:48 volumioz volumio[802]: at ChildProcess.exithandler (child_process.js:308:12) Jul 22 12:28:48 volumioz volumio[802]: at ChildProcess.emit (events.js:315:20) Jul 22 12:28:48 volumioz volumio[802]: at maybeClose (internal/child_process.js:1048:16) Jul 22 12:28:48 volumioz volumio[802]: at Socket. (internal/child_process.js:439:11) Jul 22 12:28:48 volumioz volumio[802]: at Socket.emit (events.js:315:20) Jul 22 12:28:48 volumioz volumio[802]: at Pipe. (net.js:673:12) Jul 22 12:28:48 volumioz wireless.js[1161]: Stopped aP Jul 22 12:28:48 volumioz sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 22 12:28:48 volumioz sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:48 volumioz sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 22 12:28:48 volumioz sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:48 volumioz kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 22 12:28:48 volumioz sudo[1196]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:48 volumioz volumio[802]: info: Discovery: Started advertising with name: VolumioZ Jul 22 12:28:48 volumioz sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 22 12:28:48 volumioz sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 22 12:28:48 volumioz sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:48 volumioz sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:48 volumioz volumio[802]: info: Discovery: Started advertising with name: VolumioZ Jul 22 12:28:48 volumioz sudo[1200]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:49 volumioz sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 22 12:28:49 volumioz sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:49 volumioz sudo[1210]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:49 volumioz sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 22 12:28:49 volumioz sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:49 volumioz ntpd[702]: Deleting interface #3 wlan0, 192.168.1.45#123, interface stats: received=6513, sent=6514, dropped=0, active_time=1029203 secs Jul 22 12:28:49 volumioz ntpd[702]: 116.203.187.86 local addr 192.168.1.45 -> Jul 22 12:28:49 volumioz ntpd[702]: 79.133.44.138 local addr 192.168.1.45 -> Jul 22 12:28:49 volumioz ntpd[702]: 85.214.83.151 local addr 192.168.1.45 -> Jul 22 12:28:49 volumioz ntpd[702]: 193.175.73.20 local addr 192.168.1.45 -> Jul 22 12:28:49 volumioz ntpd[702]: 167.235.69.67 local addr 192.168.1.45 -> Jul 22 12:28:49 volumioz ntpd[702]: 85.215.189.120 local addr 192.168.1.45 -> Jul 22 12:28:49 volumioz sudo[1212]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:49 volumioz wireless.js[1161]: SETTING APPROPRIATE REG DOMAIN: DE Jul 22 12:28:49 volumioz sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 22 12:28:49 volumioz sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:49 volumioz sudo[1218]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:49 volumioz sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jul 22 12:28:49 volumioz sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:50 volumioz sudo[1220]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:50 volumioz wireless.js[1161]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jul 22 12:28:50 volumioz wireless.js[1161]: Start wireless flow Jul 22 12:28:50 volumioz wireless.js[1161]: Stopped hotspot (if there).. Jul 22 12:28:50 volumioz sudo[1225]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 22 12:28:50 volumioz sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:50 volumioz sudo[1225]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:50 volumioz sudo[1227]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 22 12:28:50 volumioz sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:50 volumioz sudo[1227]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:50 volumioz wireless.js[1161]: DHCP IP Jul 22 12:28:50 volumioz wireless.js[1161]: Start ap Jul 22 12:28:50 volumioz sudo[1230]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 22 12:28:50 volumioz sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:51 volumioz wpa_supplicant[1229]: Successfully initialized wpa_supplicant Jul 22 12:28:51 volumioz kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 22 12:28:51 volumioz wireless.js[1161]: trying... Jul 22 12:28:51 volumioz sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:51 volumioz sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:52 volumioz dhcpcd[1231]: main: control_open: Connection refused Jul 22 12:28:52 volumioz sudo[1233]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:52 volumioz dhcpcd[1231]: dev: loaded udev Jul 22 12:28:52 volumioz dhcpcd[1231]: wlan0: connected to Access Point `' Jul 22 12:28:52 volumioz wireless.js[1161]: trying... Jul 22 12:28:53 volumioz sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:53 volumioz sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:53 volumioz sudo[1247]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:53 volumioz dhcpcd[1231]: no interfaces have a carrier Jul 22 12:28:53 volumioz dhcpcd[1269]: wlan0: waiting for carrier Jul 22 12:28:53 volumioz dhcpcd[1231]: forked to background, child pid 1269 Jul 22 12:28:53 volumioz sudo[1230]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:53 volumioz wpa_supplicant[1242]: wlan0: Trying to associate with SSID 'FRITZ.Box 7530' Jul 22 12:28:53 volumioz wireless.js[1161]: trying... Jul 22 12:28:53 volumioz sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:53 volumioz sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:53 volumioz sudo[1271]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:54 volumioz wpa_supplicant[1242]: wlan0: Associated with dc:39:6f:22:03:1f Jul 22 12:28:54 volumioz wpa_supplicant[1242]: wlan0: CTRL-EVENT-CONNECTED - Connection to dc:39:6f:22:03:1f completed [id=0 id_str=] Jul 22 12:28:54 volumioz dhcpcd[1269]: wlan0: carrier acquired Jul 22 12:28:54 volumioz dhcpcd[1269]: wlan0: connected to Access Point `FRITZ.Box 7530' Jul 22 12:28:54 volumioz wpa_supplicant[1242]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 22 12:28:54 volumioz wpa_supplicant[1242]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jul 22 12:28:54 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jul 22 12:28:54 volumioz dhcpcd[1269]: DUID 00:01:00:01:2a:5e:f1:4b:b8:27:eb:e2:85:3b Jul 22 12:28:54 volumioz dhcpcd[1269]: wlan0: IAID eb:e2:85:3b Jul 22 12:28:54 volumioz dhcpcd[1269]: wlan0: adding address fe80::ba27:ebff:fee2:853b Jul 22 12:28:54 volumioz dhcpcd[1269]: ipv6_addaddr1: Permission denied Jul 22 12:28:54 volumioz dhcpcd[1269]: wlan0: probing address 192.168.1.45/24 Jul 22 12:28:54 volumioz volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jul 22 12:28:54 volumioz volumio[802]: info: Refreshing Cached IP Addresses Jul 22 12:28:54 volumioz sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jul 22 12:28:54 volumioz sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:54 volumioz dhcpcd[1269]: wlan0: soliciting an IPv6 router Jul 22 12:28:54 volumioz volumio[802]: error: Upnp client error: Error: This socket has been ended by the other party Jul 22 12:28:54 volumioz sudo[1275]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:54 volumioz sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 12:28:54 volumioz sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:54 volumioz sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 12:28:54 volumioz sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:54 volumioz sudo[1280]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:54 volumioz sudo[1277]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:54 volumioz wireless.js[1161]: trying... Jul 22 12:28:54 volumioz sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:54 volumioz sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:54 volumioz sudo[1286]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:55 volumioz wireless.js[1161]: trying... Jul 22 12:28:55 volumioz sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:55 volumioz sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:55 volumioz sudo[1289]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:56 volumioz wireless.js[1161]: trying... Jul 22 12:28:56 volumioz sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:56 volumioz sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:56 volumioz sudo[1292]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:57 volumioz wireless.js[1161]: trying... Jul 22 12:28:57 volumioz sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:57 volumioz sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:57 volumioz sudo[1295]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:58 volumioz wireless.js[1161]: trying... Jul 22 12:28:58 volumioz sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:58 volumioz sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:58 volumioz sudo[1298]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:59 volumioz dhcpcd[1269]: wlan0: using static address 192.168.1.45/24 Jul 22 12:28:59 volumioz avahi-daemon[381]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.45. Jul 22 12:28:59 volumioz dhcpcd[1269]: wlan0: adding route to 192.168.1.0/24 Jul 22 12:28:59 volumioz avahi-daemon[381]: New relevant interface wlan0.IPv4 for mDNS. Jul 22 12:28:59 volumioz avahi-daemon[381]: Registering new address record for 192.168.1.45 on wlan0.IPv4. Jul 22 12:28:59 volumioz dhcpcd[1269]: wlan0: adding default route via 192.168.1.1 Jul 22 12:28:59 volumioz wireless.js[1161]: trying... Jul 22 12:28:59 volumioz sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 12:28:59 volumioz sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:28:59 volumioz sudo[1322]: pam_unix(sudo:session): session closed for user root Jul 22 12:28:59 volumioz wireless.js[1161]: Connected to: ----FRITZ.Box 7530 Jul 22 12:28:59 volumioz wireless.js[1161]: ---- Jul 22 12:29:00 volumioz sudo[1325]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 12:29:00 volumioz sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 12:29:00 volumioz sudo[1325]: pam_unix(sudo:session): session closed for user root Jul 22 12:29:00 volumioz wireless.js[1161]: ... joined AP, wlan0 IPv4 is 192.168.1.45, ipV6 is undefined Jul 22 12:29:00 volumioz wireless.js[1161]: It's done! AP Jul 22 12:29:00 volumioz kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 22 12:29:00 volumioz systemd[1]: Started Wireless Services. Jul 22 12:29:00 volumioz sudo[1154]: pam_unix(sudo:session): session closed for user root Jul 22 12:29:00 volumioz volumio[802]: info: Discovery: adding 77044124-860c-43b3-845b-fb3e4bec9604 Jul 22 12:29:00 volumioz volumio[802]: info: Discovery: Found device VolumioZ Jul 22 12:29:00 volumioz volumio[802]: info: CoreCommandRouter::volumioGetState Jul 22 12:29:00 volumioz volumio[802]: info: CorePlayQueue::getTrack 0 Jul 22 12:29:00 volumioz volumio[802]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 12:29:00 volumioz volumio[802]: Error: The value NaN is not a number Jul 22 12:29:00 volumioz volumio[802]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jul 22 12:29:00 volumioz volumio[802]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jul 22 12:29:00 volumioz volumio[802]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jul 22 12:29:00 volumioz volumio[802]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jul 22 12:29:00 volumioz volumio[802]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jul 22 12:29:00 volumioz volumio[802]: at Browser.emit (events.js:315:20) Jul 22 12:29:00 volumioz volumio[802]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 22 12:29:00 volumioz volumio[802]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 22 12:29:00 volumioz volumio[802]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jul 22 12:29:00 volumioz volumio[802]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jul 22 12:29:00 volumioz volumio[802]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jul 22 12:29:00 volumioz volumio[802]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jul 22 12:29:00 volumioz volumio[802]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jul 22 12:29:00 volumioz volumio[802]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 12:29:00 volumioz ntpd[702]: Listen normally on 4 wlan0 192.168.1.45:123 Jul 22 12:29:00 volumioz ntpd[702]: new interface(s) found: waking up resolver Jul 22 12:29:03 volumioz sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-22 12:28 Jul 22 12:29:03 volumioz sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"