-- Logs begin at Sat 2024-05-04 10:11:03 JST, end at Sat 2024-05-04 10:35:08 JST. -- May 04 10:34:00 volumio wpa_supplicant[616]: wlan0: Failed to initiate sched scan May 04 10:34:00 volumio systemd[1]: mpd.service: Succeeded. May 04 10:34:00 volumio systemd[1]: Stopped Music Player Daemon. May 04 10:34:01 volumio systemd[1]: Starting Music Player Daemon... May 04 10:34:01 volumio volumio-remote-updater[343]: [2024-05-04 10:34:01] [connect] Successful connection May 04 10:34:02 volumio volumio[4954]: info: Loading plugin "networkfs"... May 04 10:34:05 volumio wpa_supplicant[616]: wlan0: Trying to associate with SSID 'TP-Link_D27C' May 04 10:34:06 volumio wpa_supplicant[616]: wlan0: Associated with 68:ff:7b:a7:30:58 May 04 10:34:06 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-CONNECTED - Connection to 68:ff:7b:a7:30:58 completed [id=0 id_str=] May 04 10:34:06 volumio dhcpcd[641]: wlan0: carrier acquired May 04 10:34:06 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 04 10:34:06 volumio dhcpcd[641]: wlan0: IAID eb:9d:8c:b3 May 04 10:34:06 volumio dhcpcd[641]: wlan0: rebinding lease of 192.168.1.68 May 04 10:34:06 volumio dhcpcd[641]: wlan0: probing address 192.168.1.68/24 May 04 10:34:07 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP May 04 10:34:07 volumio volumio[4954]: info: Starting Udev Watcher for removable devices May 04 10:34:07 volumio dhcpcd[641]: wlan0: soliciting an IPv6 router May 04 10:34:09 volumio volumio[4954]: info: Ignoring mount for partition: boot May 04 10:34:09 volumio volumio[4954]: info: Ignoring mount for partition: volumio May 04 10:34:09 volumio volumio[4954]: info: Ignoring mount for partition: volumio_data May 04 10:34:09 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 10:34:09 volumio volumio[4954]: info: Loading plugin "alarm-clock"... May 04 10:34:11 volumio dhcpcd[641]: wlan0: leased 192.168.1.68 for 259200 seconds May 04 10:34:11 volumio avahi-daemon[347]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.68. May 04 10:34:11 volumio dhcpcd[641]: wlan0: adding route to 192.168.1.0/24 May 04 10:34:11 volumio avahi-daemon[347]: New relevant interface wlan0.IPv4 for mDNS. May 04 10:34:11 volumio avahi-daemon[347]: Registering new address record for 192.168.1.68 on wlan0.IPv4. May 04 10:34:11 volumio dhcpcd[641]: wlan0: adding default route via 192.168.1.1 May 04 10:34:11 volumio volumio[4954]: info: Loading plugin "airplay_emulation"... May 04 10:34:11 volumio volumio[4954]: info: Starting Shairport Sync May 04 10:34:12 volumio volumio[4954]: info: Loading plugin "last_100"... May 04 10:34:12 volumio volumio[4954]: info: Loading plugin "webradio"... May 04 10:34:12 volumio ntpd[484]: Listen normally on 4 wlan0 192.168.1.68:123 May 04 10:34:12 volumio ntpd[484]: new interface(s) found: waking up resolver May 04 10:34:13 volumio volumio[4954]: info: Loading plugin "i2s_dacs"... May 04 10:34:13 volumio volumio[4954]: info: Loading plugin "volumiodiscovery"... May 04 10:34:13 volumio volumio[4954]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 10:34:13 volumio node[4954]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 10:34:13 volumio volumio[4954]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 10:34:13 volumio node[4954]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 10:34:13 volumio volumio[4954]: *** WARNING *** For more information see May 04 10:34:13 volumio node[4954]: *** WARNING *** For more information see May 04 10:34:13 volumio volumio[4954]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 10:34:13 volumio volumio[4954]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 10:34:13 volumio volumio[4954]: *** WARNING *** For more information see May 04 10:34:13 volumio node[4954]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 10:34:13 volumio node[4954]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 10:34:13 volumio node[4954]: *** WARNING *** For more information see May 04 10:34:13 volumio volumio[4954]: info: Applying required configuration parameters for plugin volumiodiscovery May 04 10:34:13 volumio volumio[4954]: info: Discovery: Started advertising with name: Volumio May 04 10:34:13 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 10:34:13 volumio volumio[4954]: info: Loading plugin "spop"... May 04 10:34:16 volumio volumio-remote-updater[343]: [2024-05-04 10:34:16] [connect] Successful connection May 04 10:34:16 volumio mpd[4999]: May 04 10:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 04 10:34:16 volumio mpd[4999]: May 04 10:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 04 10:34:16 volumio mpd[4999]: May 04 10:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 04 10:34:16 volumio systemd[1]: Started Music Player Daemon. May 04 10:34:16 volumio sudo[4994]: pam_unix(sudo:session): session closed for user root May 04 10:34:21 volumio volumio[4954]: info: Loading plugin "squeezelite_mc"... May 04 10:34:26 volumio volumio[4954]: info: Loading plugin "outputs"... May 04 10:34:26 volumio volumio[4954]: info: Loading plugin "albumart"... May 04 10:34:26 volumio volumio[4954]: info: Plugin example_plugin is not enabled May 04 10:34:26 volumio volumio[4954]: info: Loading plugin "inputs"... May 04 10:34:26 volumio volumio[4954]: info: Loading plugin "updater_comm"... May 04 10:34:27 volumio squeezeboxserver[1095]: server/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:27 volumio squeezeboxserver[1095]: ; fh=IO::Socket::INET=GLOB(0x485f398) May 04 10:34:27 volumio squeezeboxserver[1095]: [24-05-04 10:32:52.4510] main:: ([undef]) Warning: [10:32:52.4306] EV: error in callback (ignoring): Cannot write to '/var/log/squeezeboxserver/server.log': Illegal seek at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:27 volumio squeezeboxserver[1095]: [24-05-04 10:32:52.4539] main:: ([undef]) Warning: (in cleanup) Cannot write to '/var/log/squeezeboxserver/server.log': Illegal seek at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:27 volumio squeezeboxserver[1095]: [24-05-04 10:33:30.6729] main:: ([undef]) [adts @ 0x21944c0] Application provided invalid, non monotonically increasing dts to muxer in stream 0: 4578416640 >= -2814225 May 04 10:34:27 volumio squeezeboxserver[1095]: [24-05-04 10:33:30.7257] main:: ([undef]) Error: Select task failed calling Slim::Web::HTTP::sendStreamingResponse: Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241, line 3. May 04 10:34:27 volumio squeezeboxserver[1095]: ; fh=Slim::Web::HTTP::ClientConn=GLOB(0x6407128) May 04 10:34:27 volumio squeezeboxserver[1095]: [24-05-04 10:33:30.7295] main:: ([undef]) Warning: [10:33:30.7282] EV: error in callback (ignoring): Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:27 volumio squeezeboxserver[1095]: [24-05-04 10:33:30.7434] main:: ([undef]) Warning: (in cleanup) Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:30 volumio volumio[4954]: info: Plugin mpdemulation is not enabled May 04 10:34:30 volumio volumio[4954]: info: Loading plugin "rest_api"... May 04 10:34:30 volumio volumio[4954]: info: Loading plugin "websocket"... May 04 10:34:30 volumio volumio[4954]: info: Loading plugin "lms"... May 04 10:34:31 volumio volumio-remote-updater[343]: [2024-05-04 10:34:31] [connect] Successful connection May 04 10:34:31 volumio volumio[4954]: Forking 1 albumart workers May 04 10:34:34 volumio volumio[4954]: info: Loading i18n strings for locale ja May 04 10:34:34 volumio volumio[4954]: Updating browse sources language May 04 10:34:34 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 10:34:35 volumio squeezeboxserver[1095]: [info] ffmpeg started on 2024-05-04 at 10:34:35 May 04 10:34:35 volumio squeezeboxserver[1095]: Report written to "/var/lib/squeezeboxserver/cache/tmp/ffmpeg-20240504-103427.log" May 04 10:34:36 volumio squeezeboxserver[1095]: [http @ 0x12628c0] [warning] HTTP error 403 Forbidden May 04 10:34:36 volumio squeezeboxserver[1095]: [error] http://f-radiko.smartstream.ne.jp/ZIP-FM/_definst_/simul-stream.stream/playlist.m3u8: Server returned 403 Forbidden (access denied) May 04 10:34:38 volumio squeezeboxserver[1095]: (in cleanup) Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::initPlayerControls May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:38 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 10:34:38 volumio volumio[4954]: Express server listening on port 3000 May 04 10:34:38 volumio volumio[4954]: [Metrics] WebUI: 57s 527.13ms May 04 10:34:39 volumio volumio[4954]: info: CoreStateMachine::resetVolumioState May 04 10:34:39 volumio volumio[4954]: info: CoreStateMachine::getcurrentVolume May 04 10:34:39 volumio volumio[4954]: info: CoreCommandRouter::volumioRetrievevolume May 04 10:34:39 volumio volumio[4954]: info: CoreStateMachine::pushState May 04 10:34:39 volumio volumio[4954]: info: CorePlayQueue::getTrack 0 May 04 10:34:39 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 10:34:39 volumio volumio[4954]: info: CoreCommandRouter::volumioPushState May 04 10:34:40 volumio sudo[5051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 10:34:40 volumio sudo[5053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 10:34:40 volumio sudo[5051]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 10:34:40 volumio sudo[5053]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 10:34:40 volumio sudo[5053]: pam_unix(sudo:session): session closed for user root May 04 10:34:40 volumio sudo[5051]: pam_unix(sudo:session): session closed for user root May 04 10:34:40 volumio volumio[4954]: info: MPD Permissions set May 04 10:34:42 volumio volumio[4954]: info: MPD running with PID4999 May 04 10:34:42 volumio volumio[4954]: ,establishing connection May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 10:34:42 volumio volumio[4954]: info: Reloading queue from file May 04 10:34:42 volumio volumio[4954]: error: updateQueue error: null May 04 10:34:42 volumio volumio[4954]: info: CoreStateMachine::setRepeat true single undefined May 04 10:34:42 volumio volumio[4954]: info: CoreStateMachine::pushState May 04 10:34:42 volumio volumio[4954]: info: CorePlayQueue::getTrack 0 May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::volumioPushState May 04 10:34:42 volumio volumio[4954]: info: CoreStateMachine::setRandom null May 04 10:34:42 volumio volumio[4954]: info: CoreStateMachine::pushState May 04 10:34:42 volumio volumio[4954]: info: CorePlayQueue::getTrack 0 May 04 10:34:42 volumio volumio[4954]: info: CoreCommandRouter::volumioPushState May 04 10:34:42 volumio volumio[4954]: info: Setting Device type: Raspberry PI May 04 10:34:43 volumio volumio[4954]: info: Completed loading Core Plugins May 04 10:34:43 volumio volumio[4954]: info: Preparing to generate the ALSA configuration file May 04 10:34:43 volumio squeezeboxserver[1095]: [24-05-04 10:34:38.1543] main:: ([undef]) [http @ 0x12628c0] HTTP error 403 Forbidden May 04 10:34:43 volumio squeezeboxserver[1095]: [24-05-04 10:34:38.1581] main:: ([undef]) Error: Timer Plugins::RadiRuKo::FFLogger::_do_watch failed: Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241, line 1. May 04 10:34:43 volumio squeezeboxserver[1095]: [24-05-04 10:34:38.1661] main:: ([undef]) Warning: [10:34:38.1649] EV: error in callback (ignoring): Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:43 volumio squeezeboxserver[1095]: [24-05-04 10:34:38.1691] main:: ([undef]) Warning: (in cleanup) Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:43 volumio volumio[4954]: error: updateQueue error: null May 04 10:34:44 volumio volumio[4954]: info: Discovery: adding 9198fd84-1187-42c0-a102-b1715939a982 May 04 10:34:44 volumio volumio[4954]: info: Discovery: Found device Volumio May 04 10:34:44 volumio volumio[4954]: info: CoreCommandRouter::volumioGetState May 04 10:34:44 volumio volumio[4954]: info: CorePlayQueue::getTrack 0 May 04 10:34:44 volumio volumio[4954]: info: Asound.conf file unchanged, so no further update is needed May 04 10:34:44 volumio sudo[5073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 04 10:34:44 volumio sudo[5073]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 10:34:44 volumio volumio[4954]: info: Output device has changed, restarting MPD May 04 10:34:44 volumio volumio[4954]: Starting albumart workers May 04 10:34:44 volumio sudo[5073]: pam_unix(sudo:session): session closed for user root May 04 10:34:45 volumio volumio[4954]: info: Output device has changed, restarting Shairport Sync May 04 10:34:45 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:45 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 10:34:45 volumio sudo[5076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 10:34:45 volumio sudo[5076]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 10:34:45 volumio sudo[5076]: pam_unix(sudo:session): session closed for user root May 04 10:34:45 volumio sudo[5078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 10:34:45 volumio sudo[5078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 10:34:45 volumio systemd[1]: Stopping Music Player Daemon... May 04 10:34:46 volumio systemd[1]: mpd.service: Succeeded. May 04 10:34:46 volumio systemd[1]: Stopped Music Player Daemon. May 04 10:34:46 volumio systemd[1]: Starting Music Player Daemon... May 04 10:34:46 volumio volumio-remote-updater[343]: [2024-05-04 10:34:46] [connect] Successful connection May 04 10:34:46 volumio volumio[4954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 10:34:46 volumio volumio[4954]: info: ___________ START PLUGINS ___________ May 04 10:34:46 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 10:34:46 volumio volumio[4954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 10:34:46 volumio volumio[4954]: info: [1714786486785] CoreMusicLibrary::Adding element Media Servers May 04 10:34:46 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 10:34:47 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:47 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 10:34:48 volumio volumio[4954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 10:34:48 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:48 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 10:34:49 volumio squeezeboxserver[1095]: [info] ffmpeg started on 2024-05-04 at 10:34:49 May 04 10:34:49 volumio squeezeboxserver[1095]: Report written to "/var/lib/squeezeboxserver/cache/tmp/ffmpeg-20240504-103443.log" May 04 10:34:49 volumio volumio[4954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 10:34:49 volumio volumio[4954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 10:34:49 volumio volumio[4954]: info: [1714786489555] CoreMusicLibrary::Adding element Last_100 May 04 10:34:49 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 10:34:49 volumio squeezeboxserver[1095]: [http @ 0x20788c0] [warning] HTTP error 403 Forbidden May 04 10:34:49 volumio squeezeboxserver[1095]: [error] http://f-radiko.smartstream.ne.jp/ZIP-FM/_definst_/simul-stream.stream/playlist.m3u8: Server returned 403 Forbidden (access denied) May 04 10:34:49 volumio volumio[4954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 10:34:49 volumio volumio[4954]: info: [1714786489657] CoreMusicLibrary::Adding element Webradio May 04 10:34:49 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 10:34:49 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 10:34:49 volumio squeezeboxserver[1095]: (in cleanup) Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:49 volumio volumio[4954]: info: Checking for old spotify connect plugin installed May 04 10:34:50 volumio volumio[4954]: info: [squeezelite_mc] Starting proxy server... May 04 10:34:50 volumio volumio[4954]: info: Volumio Calling Home May 04 10:34:51 volumio sudo[5096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver May 04 10:34:51 volumio sudo[5096]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 10:34:51 volumio systemd[1]: Stopping Logitech Media Server Daemon... May 04 10:34:53 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 10:34:54 volumio volumio[4954]: [SpotifyConnect] Creating VLS config file May 04 10:34:54 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-DISCONNECTED bssid=68:ff:7b:a7:30:58 reason=2 May 04 10:34:54 volumio dhcpcd[641]: wlan0: carrier lost May 04 10:34:54 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 04 10:34:54 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP May 04 10:34:54 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:54 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:54 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:54 volumio volumio[4954]: [SpotifyConnect] Starting metadata listener May 04 10:34:55 volumio wpa_supplicant[616]: wlan0: Trying to associate with SSID 'TP-Link_D27C' May 04 10:34:55 volumio avahi-daemon[347]: Withdrawing address record for 192.168.1.68 on wlan0. May 04 10:34:55 volumio avahi-daemon[347]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.68. May 04 10:34:55 volumio avahi-daemon[347]: Interface wlan0.IPv4 no longer relevant for mDNS. May 04 10:34:55 volumio dhcpcd[641]: wlan0: deleting route to 192.168.1.0/24 May 04 10:34:55 volumio dhcpcd[641]: wlan0: deleting default route via 192.168.1.1 May 04 10:34:55 volumio volumio[4954]: info: [squeezelite_mc] Proxy server started on port 35577 May 04 10:34:56 volumio wpa_supplicant[616]: wlan0: Associated with 68:ff:7b:a7:30:58 May 04 10:34:56 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-CONNECTED - Connection to 68:ff:7b:a7:30:58 completed [id=0 id_str=] May 04 10:34:56 volumio dhcpcd[641]: wlan0: carrier acquired May 04 10:34:56 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 04 10:34:56 volumio sudo[5121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 04 10:34:56 volumio sudo[5121]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 10:34:56 volumio dhcpcd[641]: wlan0: IAID eb:9d:8c:b3 May 04 10:34:56 volumio dhcpcd[641]: wlan0: soliciting an IPv6 router May 04 10:34:56 volumio wpa_supplicant[616]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP May 04 10:34:57 volumio dhcpcd[641]: wlan0: rebinding lease of 192.168.1.68 May 04 10:34:57 volumio dhcpcd[641]: wlan0: probing address 192.168.1.68/24 May 04 10:34:57 volumio sudo[5121]: pam_unix(sudo:session): session closed for user root May 04 10:34:58 volumio squeezeboxserver[1095]: [24-05-04 10:34:49.7795] main:: ([undef]) [http @ 0x20788c0] HTTP error 403 Forbidden May 04 10:34:58 volumio squeezeboxserver[1095]: [24-05-04 10:34:49.7968] main:: ([undef]) Error: Timer Plugins::RadiRuKo::FFLogger::_do_watch failed: Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241, line 5. May 04 10:34:58 volumio squeezeboxserver[1095]: [24-05-04 10:34:49.8006] main:: ([undef]) Warning: [10:34:49.7994] EV: error in callback (ignoring): Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:58 volumio squeezeboxserver[1095]: [24-05-04 10:34:49.8202] main:: ([undef]) Warning: (in cleanup) Cannot write to '/var/log/squeezeboxserver/server.log': Inappropriate ioctl for device at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender/File.pm line 241. May 04 10:34:58 volumio ntpd[484]: Deleting interface #4 wlan0, 192.168.1.68#123, interface stats: received=36, sent=36, dropped=0, active_time=46 secs May 04 10:34:58 volumio ntpd[484]: 139.162.96.56 local addr 192.168.1.68 -> May 04 10:34:58 volumio ntpd[484]: 172.105.240.243 local addr 192.168.1.68 -> May 04 10:34:58 volumio ntpd[484]: 133.243.238.163 local addr 192.168.1.68 -> May 04 10:34:58 volumio ntpd[484]: 133.130.121.141 local addr 192.168.1.68 -> May 04 10:34:58 volumio ntpd[484]: 45.76.218.37 local addr 192.168.1.68 -> May 04 10:34:58 volumio ntpd[484]: 133.243.238.243 local addr 192.168.1.68 -> May 04 10:34:58 volumio volumio[4954]: info: CoreCommandRouter::volumioRetrievevolume May 04 10:34:58 volumio volumio[4954]: info: CoreStateMachine::pushState May 04 10:34:59 volumio volumio[4954]: info: CorePlayQueue::getTrack 0 May 04 10:34:59 volumio volumio[4954]: info: CoreCommandRouter::volumioPushState May 04 10:34:59 volumio volumio[4954]: info: [squeezelite_mc] Server discovery started May 04 10:34:59 volumio volumio[4954]: info: [squeezelite_mc] Player finder started May 04 10:34:59 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:59 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 10:34:59 volumio volumio[4954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 10:34:59 volumio volumio[4954]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true May 04 10:34:59 volumio volumio[4954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 10:35:00 volumio volumio[4954]: Error: send ENETUNREACH 255.255.255.255:3483 May 04 10:35:00 volumio volumio[4954]: at doSend (dgram.js:692:16) May 04 10:35:00 volumio volumio[4954]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 04 10:35:00 volumio volumio[4954]: at afterDns (dgram.js:638:5) May 04 10:35:00 volumio volumio[4954]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 04 10:35:00 volumio volumio[4954]: errno: -101, May 04 10:35:00 volumio volumio[4954]: code: 'ENETUNREACH', May 04 10:35:00 volumio volumio[4954]: syscall: 'send', May 04 10:35:00 volumio volumio[4954]: address: '255.255.255.255', May 04 10:35:00 volumio volumio[4954]: port: 3483 May 04 10:35:00 volumio volumio[4954]: } May 04 10:35:00 volumio volumio[4954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 10:35:01 volumio volumio-remote-updater[343]: [2024-05-04 10:35:01] [connect] Successful connection May 04 10:35:01 volumio squeezeboxserver[1095]: (in cleanup) (in cleanup) at /usr/share/squeezeboxserver/lib/Log/Log4perl/Logger.pm line 580 during global destruction. May 04 10:35:02 volumio dhcpcd[641]: wlan0: leased 192.168.1.68 for 259200 seconds May 04 10:35:02 volumio avahi-daemon[347]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.68. May 04 10:35:02 volumio dhcpcd[641]: wlan0: adding route to 192.168.1.0/24 May 04 10:35:02 volumio avahi-daemon[347]: New relevant interface wlan0.IPv4 for mDNS. May 04 10:35:02 volumio avahi-daemon[347]: Registering new address record for 192.168.1.68 on wlan0.IPv4. May 04 10:35:02 volumio dhcpcd[641]: wlan0: adding default route via 192.168.1.1 May 04 10:35:04 volumio ntpd[484]: Listen normally on 5 wlan0 192.168.1.68:123 May 04 10:35:04 volumio ntpd[484]: new interface(s) found: waking up resolver May 04 10:35:04 volumio mpd[5089]: May 04 10:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 04 10:35:04 volumio mpd[5089]: May 04 10:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 04 10:35:04 volumio mpd[5089]: May 04 10:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 04 10:35:04 volumio systemd[1]: Started Music Player Daemon. May 04 10:35:04 volumio sudo[5078]: pam_unix(sudo:session): session closed for user root May 04 10:35:08 volumio sudo[5183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 10:34 May 04 10:35:08 volumio sudo[5183]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"