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