-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sat 2024-07-13 08:36:21 JST. -- Jul 13 08:35:00 volumio volumio[7807]: info: Loading plugin "youtube2"... Jul 13 08:35:01 volumio volumio[7807]: info: Plugin ytmusic is not enabled Jul 13 08:35:01 volumio volumio[7807]: info: Loading plugin "outputs"... Jul 13 08:35:01 volumio volumio[7807]: info: Loading plugin "albumart"... Jul 13 08:35:01 volumio volumio[7807]: info: Plugin example_plugin is not enabled Jul 13 08:35:01 volumio volumio[7807]: info: Loading plugin "inputs"... Jul 13 08:35:01 volumio volumio[7807]: info: Loading plugin "updater_comm"... Jul 13 08:35:01 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="A0957F891B71-2G" Jul 13 08:35:01 volumio wpa_supplicant[1118]: wlan0: Trying to associate with SSID 'A0957F891B71-2G' Jul 13 08:35:01 volumio volumio[7807]: info: Plugin mpdemulation is not enabled Jul 13 08:35:01 volumio volumio[7807]: info: Loading plugin "rest_api"... Jul 13 08:35:01 volumio volumio[7807]: info: Loading plugin "websocket"... Jul 13 08:35:01 volumio volumio[7807]: info: Loading i18n strings for locale ja Jul 13 08:35:01 volumio volumio[7807]: Updating browse sources language Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::initPlayerControls Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 08:35:01 volumio volumio[7807]: Express server listening on port 3000 Jul 13 08:35:01 volumio volumio[7807]: [Metrics] WebUI: 8s 504.29ms Jul 13 08:35:01 volumio volumio[7807]: info: CoreStateMachine::resetVolumioState Jul 13 08:35:01 volumio volumio[7807]: info: CoreStateMachine::getcurrentVolume Jul 13 08:35:01 volumio volumio[7807]: info: CoreCommandRouter::volumioRetrievevolume Jul 13 08:35:02 volumio ntpd[925]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 13 08:35:02 volumio volumio[7807]: info: MPD Permissions set Jul 13 08:35:02 volumio volumio[7807]: Forking 3 albumart workers Jul 13 08:35:02 volumio volumio[7807]: info: MPD running with PID7851 Jul 13 08:35:02 volumio volumio[7807]: ,establishing connection Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:02 volumio volumio[7807]: info: Executing start script for DAC IQaudIO DAC Plus Jul 13 08:35:02 volumio volumio[7807]: info: Reloading queue from file Jul 13 08:35:02 volumio volumio[7807]: error: updateQueue error: null Jul 13 08:35:03 volumio volumio[7807]: Starting albumart workers Jul 13 08:35:03 volumio volumio[7807]: info: CoreStateMachine::setRepeat false single undefined Jul 13 08:35:03 volumio volumio[7807]: info: CoreStateMachine::pushState Jul 13 08:35:03 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:03 volumio volumio[7807]: info: CoreStateMachine::setRandom null Jul 13 08:35:03 volumio volumio[7807]: info: CoreStateMachine::pushState Jul 13 08:35:03 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:03 volumio volumio[7807]: Starting albumart workers Jul 13 08:35:03 volumio volumio[7807]: info: Setting Device type: Raspberry PI Jul 13 08:35:03 volumio volumio[7807]: info: DAC script executed Jul 13 08:35:03 volumio volumio[7807]: info: Completed loading Core Plugins Jul 13 08:35:03 volumio volumio[7807]: info: Preparing to generate the ALSA configuration file Jul 13 08:35:03 volumio volumio[7807]: Starting albumart workers Jul 13 08:35:03 volumio volumio[7807]: info: VolumeController:: Volume=100 Mute =false Jul 13 08:35:03 volumio volumio[7807]: info: CoreStateMachine::pushState Jul 13 08:35:03 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:03 volumio volumio[7807]: info: CoreStateMachine::updateTrackBlock Jul 13 08:35:03 volumio volumio[7807]: info: CorePlayQueue::getTrackBlock Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::volumioRetrievevolume Jul 13 08:35:03 volumio volumio[7807]: error: updateQueue error: null Jul 13 08:35:03 volumio volumio[7807]: info: Asound.conf file unchanged, so no further update is needed Jul 13 08:35:03 volumio volumio[7807]: info: Output device has changed, restarting MPD Jul 13 08:35:03 volumio volumio[7807]: info: Output device has changed, restarting Shairport Sync Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:03 volumio volumio[7807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 08:35:03 volumio volumio[7807]: info: ___________ START PLUGINS ___________ Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 08:35:03 volumio volumio[7807]: info: [1720827303588] CoreMusicLibrary::Adding element Media Servers Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:03 volumio volumio[7807]: Cannot find translation for sourceMedia Servers Jul 13 08:35:03 volumio dhcpcd[1143]: wlan0: carrier acquired Jul 13 08:35:03 volumio sudo[7917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 08:35:03 volumio wpa_supplicant[1118]: wlan0: Associated with a0:95:7f:89:1b:70 Jul 13 08:35:03 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:95:7f:89:1b:70 completed [id=0 id_str=] Jul 13 08:35:03 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 13 08:35:03 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Jul 13 08:35:03 volumio dhcpcd[1143]: wlan0: IAID eb:ba:e8:23 Jul 13 08:35:03 volumio dhcpcd[1143]: wlan0: carrier lost Jul 13 08:35:03 volumio sudo[7919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 08:35:03 volumio sudo[7919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:03 volumio sudo[7917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:03 volumio sudo[7917]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:03 volumio dhcpcd[1143]: wlan0: carrier acquired Jul 13 08:35:03 volumio systemd[1]: Stopping Music Player Daemon... Jul 13 08:35:03 volumio dhcpcd[1143]: wlan0: IAID eb:ba:e8:23 Jul 13 08:35:03 volumio systemd[1]: mpd.service: Succeeded. Jul 13 08:35:03 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 08:35:03 volumio systemd[1]: Starting Music Player Daemon... Jul 13 08:35:03 volumio volumio[7807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:03 volumio dhcpcd[1143]: wlan0: rebinding lease of 192.168.3.7 Jul 13 08:35:03 volumio volumio[7807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 08:35:03 volumio volumio[7807]: info: [1720827303996] CoreMusicLibrary::Adding element Last_100 Jul 13 08:35:03 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:03 volumio volumio[7807]: Cannot find translation for sourceMedia Servers Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 08:35:04 volumio volumio[7807]: info: [1720827304003] CoreMusicLibrary::Adding element Webradio Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:04 volumio ntpd[925]: Soliciting pool server 2406:da14:10c4:9aa0:123:: Jul 13 08:35:04 volumio volumio[7807]: Cannot find translation for sourceMedia Servers Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 08:35:04 volumio volumio[7807]: info: [lmsde] Executing script: /opt/lmsde/lms.sh start Jul 13 08:35:04 volumio dhcpcd[1143]: wlan0: probing address 192.168.3.7/24 Jul 13 08:35:04 volumio volumio[7807]: info: [squeezelite_mc] Starting proxy server... Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 08:35:04 volumio volumio[7807]: info: [1720827304114] CoreMusicLibrary::Adding element YouTube2 Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:04 volumio volumio[7807]: Cannot find translation for sourceMedia Servers Jul 13 08:35:04 volumio volumio[7807]: Cannot find translation for sourceYouTube2 Jul 13 08:35:04 volumio volumio[7807]: info: Volumio Calling Home Jul 13 08:35:04 volumio sudo[7959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/opt/lmsde/lms.sh start Jul 13 08:35:04 volumio sudo[7959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:04 volumio volumio[7807]: info: [squeezelite_mc] Proxy server started on port 39269 Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::volumioRetrievevolume Jul 13 08:35:04 volumio volumio[7807]: Unhandled rejection Error: No sockets available, cannot start. Jul 13 08:35:04 volumio volumio[7807]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 13 08:35:04 volumio volumio[7807]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 13 08:35:04 volumio volumio[7807]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 13 08:35:04 volumio volumio[7807]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 13 08:35:04 volumio volumio[7807]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 13 08:35:04 volumio volumio[7807]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 13 08:35:04 volumio volumio[7807]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 13 08:35:04 volumio volumio[7807]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 13 08:35:04 volumio volumio[7807]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 13 08:35:04 volumio volumio[7807]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 13 08:35:04 volumio volumio[7807]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 13 08:35:04 volumio volumio[7807]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 13 08:35:04 volumio volumio[7807]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 13 08:35:04 volumio volumio[7807]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 13 08:35:04 volumio volumio[7807]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 13 08:35:04 volumio volumio[7807]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 13 08:35:04 volumio volumio[7807]: info: Discovery: adding 18bcd961-2cad-4825-9d0c-cefd458c8c21 Jul 13 08:35:04 volumio volumio[7807]: info: mDNS: Found device Volumio Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:04 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:04 volumio volumio[7807]: info: MPD Permissions set Jul 13 08:35:04 volumio volumio[7807]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jul 13 08:35:04 volumio volumio[7807]: info: VolumeController:: Volume=100 Mute =false Jul 13 08:35:04 volumio volumio[7807]: info: CoreStateMachine::pushState Jul 13 08:35:04 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:04 volumio volumio[7807]: info: VolumeController:: Volume=100 Mute =false Jul 13 08:35:04 volumio volumio[7807]: info: CoreStateMachine::pushState Jul 13 08:35:04 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:04 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:04 volumio dhcpcd[1143]: wlan0: soliciting an IPv6 router Jul 13 08:35:05 volumio mpd[7966]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 08:35:05 volumio mpd[7966]: output: No 'audio_output' defined in config file Jul 13 08:35:05 volumio mpd[7966]: output: Attempt to detect audio output device Jul 13 08:35:05 volumio mpd[7966]: output: Attempting to detect a alsa audio device Jul 13 08:35:05 volumio mpd[7966]: output: Successfully detected a alsa audio device Jul 13 08:35:05 volumio mpd[7966]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 13 08:35:05 volumio mpd[7966]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 13 08:35:05 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 13 08:35:06 volumio ntpd[925]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 13 08:35:06 volumio mpd[7966]: zeroconf: No global port, disabling zeroconf Jul 13 08:35:06 volumio systemd[1]: Started Music Player Daemon. Jul 13 08:35:06 volumio sudo[7919]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:06 volumio volumio[7807]: info: [squeezelite_mc] Server discovery started Jul 13 08:35:06 volumio volumio[7807]: info: [squeezelite_mc] Player finder started Jul 13 08:35:06 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:06 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:06 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:06 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 08:35:06 volumio volumio[7807]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 13 08:35:06 volumio volumio[7807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 08:35:06 volumio volumio[7807]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 13 08:35:06 volumio volumio[7807]: at doSend (dgram.js:692:16) Jul 13 08:35:06 volumio volumio[7807]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 13 08:35:06 volumio volumio[7807]: at afterDns (dgram.js:638:5) Jul 13 08:35:06 volumio volumio[7807]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 13 08:35:06 volumio volumio[7807]: errno: -101, Jul 13 08:35:06 volumio volumio[7807]: code: 'ENETUNREACH', Jul 13 08:35:06 volumio volumio[7807]: syscall: 'send', Jul 13 08:35:06 volumio volumio[7807]: address: '255.255.255.255', Jul 13 08:35:06 volumio volumio[7807]: port: 3483 Jul 13 08:35:06 volumio volumio[7807]: } Jul 13 08:35:06 volumio volumio[7807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 08:35:06 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 13 08:35:06 volumio sudo[7959]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:06 volumio sudo[8011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 08:34 Jul 13 08:35:06 volumio sudo[8011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:07 volumio sudo[8011]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 13 08:35:07 volumio systemd[1]: media-3013\x2dA0F5.mount: Succeeded. Jul 13 08:35:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 13 08:35:07 volumio systemd[1]: Started dynamicswap service. Jul 13 08:35:07 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 13 08:35:07 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 13 08:35:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jul 13 08:35:07 volumio systemd[1]: Stopped Volumio Backend Module. Jul 13 08:35:07 volumio systemd[1]: Started Volumio Backend Module. Jul 13 08:35:07 volumio systemd[1]: Started dynamicswap service. Jul 13 08:35:07 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 13 08:35:09 volumio volumio[8025]: info: ------------------------------------------- Jul 13 08:35:09 volumio volumio[8025]: info: ----- Volumio3 ---- Jul 13 08:35:09 volumio volumio[8025]: info: ------------------------------------------- Jul 13 08:35:09 volumio volumio[8025]: info: ----- System startup ---- Jul 13 08:35:09 volumio volumio[8025]: info: ------------------------------------------- Jul 13 08:35:09 volumio volumio-remote-updater[493]: [2024-07-13 08:35:09] [connect] Successful connection Jul 13 08:35:10 volumio volumio[8025]: info: MYVOLUMIO Environment detected Jul 13 08:35:10 volumio dhcpcd[1143]: wlan0: leased 192.168.3.7 for 86400 seconds Jul 13 08:35:10 volumio volumio[8025]: info: Plugin folders cleanup Jul 13 08:35:10 volumio volumio[8025]: info: Scanning into folder /volumio/app/plugins/ Jul 13 08:35:10 volumio volumio[8025]: info: Scanning category audio_interface Jul 13 08:35:10 volumio volumio[8025]: info: Scanning category miscellanea Jul 13 08:35:10 volumio volumio[8025]: info: Scanning category music_service Jul 13 08:35:10 volumio volumio[8025]: info: Scanning category plugins.json Jul 13 08:35:10 volumio volumio[8025]: info: Scanning category system_controller Jul 13 08:35:10 volumio volumio[8025]: info: Scanning category user_interface Jul 13 08:35:10 volumio volumio[8025]: info: Scanning into folder /data/plugins/ Jul 13 08:35:10 volumio volumio[8025]: info: Scanning category music_service Jul 13 08:35:10 volumio volumio[8025]: info: Plugin folders cleanup completed Jul 13 08:35:10 volumio volumio[8025]: info: ------------------------------------------- Jul 13 08:35:10 volumio volumio[8025]: info: ----- Core plugins startup ---- Jul 13 08:35:10 volumio volumio[8025]: info: ------------------------------------------- Jul 13 08:35:10 volumio volumio[8025]: info: Loading plugins from folder /volumio/app/plugins/ Jul 13 08:35:10 volumio volumio[8025]: info: Adding plugin upnp to MyMusic Plugins Jul 13 08:35:10 volumio volumio[8025]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 13 08:35:10 volumio volumio[8025]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 13 08:35:10 volumio volumio[8025]: info: Loading plugins from folder /data/plugins/ Jul 13 08:35:10 volumio volumio[8025]: info: Loading plugin "system"... Jul 13 08:35:10 volumio volumio[8025]: info: Loading plugin "appearance"... Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "network"... Jul 13 08:35:11 volumio volumio[8025]: info: Refreshing Cached IP Addresses Jul 13 08:35:11 volumio avahi-daemon[487]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. Jul 13 08:35:11 volumio dhcpcd[1143]: wlan0: adding route to 192.168.3.0/24 Jul 13 08:35:11 volumio dhcpcd[1143]: wlan0: adding default route via 192.168.3.1 Jul 13 08:35:11 volumio avahi-daemon[487]: New relevant interface wlan0.IPv4 for mDNS. Jul 13 08:35:11 volumio avahi-daemon[487]: Registering new address record for 192.168.3.7 on wlan0.IPv4. Jul 13 08:35:11 volumio sudo[8050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 13 08:35:11 volumio sudo[8050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:11 volumio sudo[8050]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:11 volumio sudo[8057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "services"... Jul 13 08:35:11 volumio sudo[8057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "alsa_controller"... Jul 13 08:35:11 volumio sudo[8057]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:11 volumio sudo[8068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 13 08:35:11 volumio sudo[8068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "wizard"... Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "volumio_command_line_client"... Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "upnp"... Jul 13 08:35:11 volumio volumio[8025]: info: [1720827311423] Starting Upmpd Daemon Jul 13 08:35:11 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "my_music"... Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "mpd"... Jul 13 08:35:11 volumio volumio[8025]: info: Creating MPD Configuration file Jul 13 08:35:11 volumio volumio[8025]: info: Loading plugin "upnp_browser"... Jul 13 08:35:11 volumio sudo[8083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 08:35:11 volumio sudo[8083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:11 volumio sudo[8083]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:11 volumio sudo[8085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 08:35:11 volumio sudo[8085]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:11 volumio systemd[1]: Stopping Music Player Daemon... Jul 13 08:35:11 volumio systemd[1]: mpd.service: Succeeded. Jul 13 08:35:11 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 08:35:11 volumio systemd[1]: Starting Music Player Daemon... Jul 13 08:35:12 volumio volumio[8025]: info: Loading plugin "networkfs"... Jul 13 08:35:12 volumio volumio[8025]: info: Starting Udev Watcher for removable devices Jul 13 08:35:12 volumio volumio[8025]: info: Ignoring mount for partition: boot Jul 13 08:35:12 volumio volumio[8025]: info: Ignoring mount for partition: volumio Jul 13 08:35:12 volumio volumio[8025]: info: Ignoring mount for partition: volumio_data Jul 13 08:35:12 volumio volumio[8025]: info: Mounting Device 3013-A0F5 Jul 13 08:35:13 volumio mpd[8090]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 08:35:13 volumio mpd[8090]: output: No 'audio_output' defined in config file Jul 13 08:35:13 volumio mpd[8090]: output: Attempt to detect audio output device Jul 13 08:35:13 volumio mpd[8090]: output: Attempting to detect a alsa audio device Jul 13 08:35:13 volumio mpd[8090]: output: Successfully detected a alsa audio device Jul 13 08:35:13 volumio mpd[8090]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 13 08:35:13 volumio mpd[8090]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 13 08:35:13 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 13 08:35:13 volumio mpd[8090]: zeroconf: No global port, disabling zeroconf Jul 13 08:35:13 volumio systemd[1]: Started Music Player Daemon. Jul 13 08:35:13 volumio sudo[8096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/3013-A0F5 -o noatime,iocharset=utf8 Jul 13 08:35:13 volumio sudo[8085]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:13 volumio sudo[8096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:13 volumio sudo[8068]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:13 volumio sudo[8096]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:13 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 13 08:35:13 volumio volumio[8025]: info: Loading plugin "alarm-clock"... Jul 13 08:35:14 volumio ntpd[925]: Listen normally on 8 wlan0 192.168.3.7:123 Jul 13 08:35:14 volumio ntpd[925]: new interface(s) found: waking up resolver Jul 13 08:35:14 volumio ntpd[925]: Soliciting pool server 167.179.119.205 Jul 13 08:35:14 volumio volumio[8025]: info: Loading plugin "airplay_emulation"... Jul 13 08:35:14 volumio volumio[8025]: info: Starting Shairport Sync Jul 13 08:35:14 volumio volumio[8025]: info: Loading plugin "last_100"... Jul 13 08:35:14 volumio volumio[8025]: info: Loading plugin "webradio"... Jul 13 08:35:14 volumio volumio[8025]: info: Loading plugin "i2s_dacs"... Jul 13 08:35:14 volumio volumio[8025]: info: Loading plugin "volumiodiscovery"... Jul 13 08:35:14 volumio volumio[8025]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 13 08:35:14 volumio volumio[8025]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 13 08:35:14 volumio volumio[8025]: *** WARNING *** For more information see Jul 13 08:35:14 volumio node[8025]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 13 08:35:14 volumio volumio[8025]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 13 08:35:14 volumio volumio[8025]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 13 08:35:14 volumio volumio[8025]: *** WARNING *** For more information see Jul 13 08:35:14 volumio node[8025]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 13 08:35:14 volumio node[8025]: *** WARNING *** For more information see Jul 13 08:35:14 volumio node[8025]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 13 08:35:14 volumio node[8025]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 13 08:35:14 volumio node[8025]: *** WARNING *** For more information see Jul 13 08:35:14 volumio volumio[8025]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 13 08:35:14 volumio volumio[8025]: info: Discovery: Started advertising with name: Volumio Jul 13 08:35:14 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 13 08:35:14 volumio volumio[8025]: info: Loading plugin "lmsde"... Jul 13 08:35:14 volumio volumio[8025]: info: Loading plugin "squeezelite_mc"... Jul 13 08:35:15 volumio volumio[8025]: info: Loading plugin "youtube2"... Jul 13 08:35:16 volumio volumio[8025]: info: Plugin ytmusic is not enabled Jul 13 08:35:16 volumio volumio[8025]: info: Loading plugin "outputs"... Jul 13 08:35:16 volumio volumio[8025]: info: Loading plugin "albumart"... Jul 13 08:35:16 volumio volumio[8025]: info: Plugin example_plugin is not enabled Jul 13 08:35:16 volumio volumio[8025]: info: Loading plugin "inputs"... Jul 13 08:35:16 volumio volumio[8025]: info: Loading plugin "updater_comm"... Jul 13 08:35:16 volumio volumio[8025]: info: Plugin mpdemulation is not enabled Jul 13 08:35:16 volumio volumio[8025]: info: Loading plugin "rest_api"... Jul 13 08:35:16 volumio volumio[8025]: info: Loading plugin "websocket"... Jul 13 08:35:16 volumio volumio[8025]: info: Loading i18n strings for locale ja Jul 13 08:35:16 volumio volumio[8025]: Updating browse sources language Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::initPlayerControls Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 08:35:16 volumio volumio[8025]: Express server listening on port 3000 Jul 13 08:35:16 volumio volumio[8025]: [Metrics] WebUI: 8s 287.32ms Jul 13 08:35:16 volumio volumio[8025]: info: CoreStateMachine::resetVolumioState Jul 13 08:35:16 volumio volumio[8025]: info: CoreStateMachine::getcurrentVolume Jul 13 08:35:16 volumio volumio[8025]: info: CoreCommandRouter::volumioRetrievevolume Jul 13 08:35:17 volumio mpd[8090]: client: [0] opened from local Jul 13 08:35:17 volumio volumio[8025]: info: MPD Permissions set Jul 13 08:35:17 volumio volumio[8025]: Forking 3 albumart workers Jul 13 08:35:17 volumio mpd[8090]: exception: No database Jul 13 08:35:17 volumio mpd[8090]: exception: No database Jul 13 08:35:17 volumio volumio[8025]: info: MPD running with PID8090 Jul 13 08:35:17 volumio volumio[8025]: ,establishing connection Jul 13 08:35:17 volumio mpd[8090]: client: [1] opened from local Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: Executing start script for DAC IQaudIO DAC Plus Jul 13 08:35:17 volumio volumio[8025]: info: Reloading queue from file Jul 13 08:35:17 volumio volumio[8025]: error: updateQueue error: null Jul 13 08:35:17 volumio volumio[8025]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jul 13 08:35:17 volumio volumio[8025]: info: Setting Device type: Raspberry PI Jul 13 08:35:17 volumio volumio[8025]: info: CoreStateMachine::setRepeat false single undefined Jul 13 08:35:17 volumio volumio[8025]: info: CoreStateMachine::pushState Jul 13 08:35:17 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:17 volumio volumio[8025]: info: CoreStateMachine::setRandom null Jul 13 08:35:17 volumio volumio[8025]: info: CoreStateMachine::pushState Jul 13 08:35:17 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:17 volumio volumio[8025]: info: VolumeController:: Volume=100 Mute =false Jul 13 08:35:17 volumio volumio[8025]: info: CoreStateMachine::pushState Jul 13 08:35:17 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:17 volumio volumio[8025]: info: CoreStateMachine::updateTrackBlock Jul 13 08:35:17 volumio volumio[8025]: info: CorePlayQueue::getTrackBlock Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::volumioRetrievevolume Jul 13 08:35:17 volumio volumio[8025]: error: updateQueue error: null Jul 13 08:35:17 volumio volumio[8025]: info: DAC script executed Jul 13 08:35:17 volumio volumio[8025]: info: Completed loading Core Plugins Jul 13 08:35:17 volumio volumio[8025]: info: Preparing to generate the ALSA configuration file Jul 13 08:35:17 volumio volumio[8025]: info: Asound.conf file unchanged, so no further update is needed Jul 13 08:35:17 volumio volumio[8025]: info: Output device has changed, restarting MPD Jul 13 08:35:17 volumio volumio[8025]: info: Output device has changed, restarting Shairport Sync Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 08:35:17 volumio volumio[8025]: info: ___________ START PLUGINS ___________ Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 08:35:17 volumio volumio[8025]: info: [1720827317836] CoreMusicLibrary::Adding element Media Servers Jul 13 08:35:17 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:17 volumio volumio[8025]: Cannot find translation for sourceMedia Servers Jul 13 08:35:18 volumio volumio[8025]: Starting albumart workers Jul 13 08:35:18 volumio volumio[8025]: Starting albumart workers Jul 13 08:35:18 volumio volumio[8025]: Starting albumart workers Jul 13 08:35:18 volumio sudo[8157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 08:35:18 volumio sudo[8160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 08:35:18 volumio sudo[8160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:18 volumio sudo[8157]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:18 volumio systemd[1]: Stopping Music Player Daemon... Jul 13 08:35:18 volumio sudo[8157]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:18 volumio systemd[1]: mpd.service: Succeeded. Jul 13 08:35:18 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 08:35:18 volumio systemd[1]: Starting Music Player Daemon... Jul 13 08:35:18 volumio volumio[8025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:18 volumio volumio[8025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 08:35:18 volumio volumio[8025]: info: [1720827318964] CoreMusicLibrary::Adding element Last_100 Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:18 volumio volumio[8025]: Cannot find translation for sourceMedia Servers Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 08:35:18 volumio volumio[8025]: info: [1720827318970] CoreMusicLibrary::Adding element Webradio Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:18 volumio volumio[8025]: Cannot find translation for sourceMedia Servers Jul 13 08:35:18 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 08:35:19 volumio volumio[8025]: info: [lmsde] Executing script: /opt/lmsde/lms.sh start Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Starting proxy server... Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 08:35:19 volumio volumio[8025]: info: [1720827319083] CoreMusicLibrary::Adding element YouTube2 Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 08:35:19 volumio volumio[8025]: Cannot find translation for sourceMedia Servers Jul 13 08:35:19 volumio volumio[8025]: Cannot find translation for sourceYouTube2 Jul 13 08:35:19 volumio volumio[8025]: info: Volumio Calling Home Jul 13 08:35:19 volumio sudo[8200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/opt/lmsde/lms.sh start Jul 13 08:35:19 volumio sudo[8200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Proxy server started on port 39285 Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::volumioRetrievevolume Jul 13 08:35:19 volumio volumio[8025]: info: Discovery: adding 18bcd961-2cad-4825-9d0c-cefd458c8c21 Jul 13 08:35:19 volumio volumio[8025]: info: mDNS: Found device Volumio Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:19 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:19 volumio volumio[8025]: info: Discovery: this is already registered, 18bcd961-2cad-4825-9d0c-cefd458c8c21 Jul 13 08:35:19 volumio volumio[8025]: info: mDNS: Found device Volumio Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:19 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:19 volumio volumio[8025]: info: MPD Permissions set Jul 13 08:35:19 volumio volumio[8025]: info: VolumeController:: Volume=100 Mute =false Jul 13 08:35:19 volumio volumio[8025]: info: CoreStateMachine::pushState Jul 13 08:35:19 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:19 volumio volumio[8025]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: VolumeController:: Volume=100 Mute =false Jul 13 08:35:19 volumio volumio[8025]: info: CoreStateMachine::pushState Jul 13 08:35:19 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Server discovery started Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Player finder started Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 13 08:35:19 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"} Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Card 2 supports the following ALSA formats: ["S16_LE","S24_LE","S32_LE"] Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","card":"2","mixerType":"Hardware","mixer":"Digital","dsdFormat":null} Jul 13 08:35:19 volumio volumio[8025]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jul 13 08:35:19 volumio volumio[8025]: info: Volumio called home Jul 13 08:35:19 volumio volumio[8025]: info: Starting Shairport Sync Jul 13 08:35:19 volumio volumio[8025]: info: Starting Shairport Sync Jul 13 08:35:19 volumio volumio[8025]: info: Starting Shairport Sync Jul 13 08:35:20 volumio mpd[8208]: Jul 13 08:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 08:35:20 volumio mpd[8208]: Jul 13 08:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 13 08:35:20 volumio mpd[8208]: Jul 13 08:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 13 08:35:20 volumio volumio[8025]: info: [squeezelite_mc] Notification listener started Jul 13 08:35:20 volumio volumio[8025]: info: [squeezelite_mc] Getting players connected to volumio (192.168.3.7) Jul 13 08:35:20 volumio volumio[8025]: info: [squeezelite_mc] Players connected to volumio (192.168.3.7): [{"id":"b8:27:eb:ba:e8:23","uuid":null,"ip":"192.168.3.7","name":"volumio","server":{"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"}}] Jul 13 08:35:21 volumio volumio[8025]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:ba:e8:23","uuid":null,"ip":"192.168.3.7","name":"volumio","server":{"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"}} Jul 13 08:35:21 volumio systemd[1]: Started Music Player Daemon. Jul 13 08:35:21 volumio sudo[8160]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio sudo[8232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 13 08:35:21 volumio sudo[8232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio sudo[8233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 13 08:35:21 volumio sudo[8233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio sudo[8229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 13 08:35:21 volumio sudo[8229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 13 08:35:21 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:21 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:21 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 13 08:35:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 13 08:35:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 13 08:35:21 volumio sudo[8232]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 13 08:35:21 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 13 08:35:21 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 13 08:35:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 13 08:35:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 13 08:35:21 volumio sudo[8229]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio volumio[8025]: info: Shairport-Sync Started Jul 13 08:35:21 volumio sudo[8233]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio volumio[8025]: Error adding Membership: Error: addMembership EINVAL Jul 13 08:35:21 volumio volumio[8025]: error: updateQueue error: null Jul 13 08:35:21 volumio sudo[8227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jul 13 08:35:21 volumio sudo[8227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio volumio[8025]: info: Shairport-Sync Started Jul 13 08:35:21 volumio sudo[8227]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio sudo[8200]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio sudo[8255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 13 08:35:21 volumio sudo[8255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio sudo[8255]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio volumio[8025]: info: [lmsde] Executed /opt/lmsde/lms.sh start Jul 13 08:35:21 volumio sudo[8257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 13 08:35:21 volumio sudo[8257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio volumio[8025]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jul 13 08:35:21 volumio sudo[8257]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio sudo[8264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 13 08:35:21 volumio sudo[8264]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio sudo[8264]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio volumio[8025]: info: Upmpdcli Daemon Started Jul 13 08:35:21 volumio sudo[8262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jul 13 08:35:21 volumio sudo[8262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio sudo[8262]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:21 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jul 13 08:35:21 volumio sudo[8268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 13 08:35:21 volumio sudo[8268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:21 volumio systemd[1]: Reloading. Jul 13 08:35:22 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 13 08:35:22 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:22 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:22 volumio sudo[8268]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:22 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 13 08:35:23 volumio sudo[8284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 13 08:35:23 volumio sudo[8284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:23 volumio sudo[8284]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:23 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Jul 13 08:35:23 volumio sudo[8287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Jul 13 08:35:23 volumio sudo[8287]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:23 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Jul 13 08:35:23 volumio volumio[8025]: info: [squeezelite_mc] 'client' notification received from volumio (192.168.3.7); type is 'disconnect' Jul 13 08:35:23 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:23 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:24 volumio systemd[1]: squeezelite.service: Succeeded. Jul 13 08:35:24 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jul 13 08:35:24 volumio sudo[8287]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:24 volumio volumio-remote-updater[493]: [2024-07-13 08:35:24] [connect] Successful connection Jul 13 08:35:24 volumio volumio-remote-updater[493]: [2024-07-13 08:35:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720827324 101 Jul 13 08:35:24 volumio volumio[8025]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Jul 13 08:35:24 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 13 08:35:24 volumio sudo[8291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 13 08:35:24 volumio sudo[8291]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:24 volumio sudo[8291]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:24 volumio volumio[8025]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Jul 13 08:35:26 volumio sudo[8295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Jul 13 08:35:26 volumio sudo[8295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:26 volumio sudo[8295]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:26 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jul 13 08:35:26 volumio sudo[8298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jul 13 08:35:26 volumio sudo[8298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:26 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jul 13 08:35:26 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jul 13 08:35:26 volumio sudo[8298]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:26 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 13 08:35:26 volumio volumio[8025]: info: [squeezelite_mc] 'client' notification received from volumio (192.168.3.7); type is 'connect' Jul 13 08:35:26 volumio volumio[8025]: info: [squeezelite_mc] Getting players connected to volumio (192.168.3.7) Jul 13 08:35:26 volumio volumio[8025]: info: [squeezelite_mc] Players connected to volumio (192.168.3.7): [{"id":"b8:27:eb:ba:e8:23","uuid":null,"ip":"192.168.3.7","name":"volumio","server":{"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"}}] Jul 13 08:35:26 volumio volumio[8025]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:ba:e8:23","uuid":null,"ip":"192.168.3.7","name":"volumio","server":{"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"}} Jul 13 08:35:26 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 13 08:35:26 volumio sudo[8305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 13 08:35:26 volumio sudo[8305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:26 volumio sudo[8305]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:27 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 13 08:35:27 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:27 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:28 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:95:7f:89:1b:70 reason=0 locally_generated=1 Jul 13 08:35:28 volumio dhcpcd[1143]: wlan0: carrier lost Jul 13 08:35:28 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 13 08:35:28 volumio sudo[8308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 13 08:35:28 volumio sudo[8308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:28 volumio sudo[8308]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:28 volumio avahi-daemon[487]: Withdrawing address record for 192.168.3.7 on wlan0. Jul 13 08:35:28 volumio avahi-daemon[487]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. Jul 13 08:35:28 volumio avahi-daemon[487]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 13 08:35:28 volumio dhcpcd[1143]: wlan0: deleting route to 192.168.3.0/24 Jul 13 08:35:28 volumio dhcpcd[1143]: wlan0: deleting default route via 192.168.3.1 Jul 13 08:35:28 volumio volumio[8025]: info: mDNS: A device disapperared from network Jul 13 08:35:28 volumio volumio[8025]: info: mDNS: Device volumio disapperared from network Jul 13 08:35:29 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 13 08:35:29 volumio sudo[8341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 13 08:35:29 volumio sudo[8341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:29 volumio sudo[8341]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:29 volumio wpa_supplicant[1118]: wlan0: Trying to associate with SSID 'A0957F891B71-2G' Jul 13 08:35:29 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 13 08:35:31 volumio ntpd[925]: Deleting interface #8 wlan0, 192.168.3.7#123, interface stats: received=10, sent=12, dropped=0, active_time=17 secs Jul 13 08:35:31 volumio ntpd[925]: 202.181.103.212 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 202.182.111.234 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 172.105.204.105 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 45.76.221.157 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 133.243.238.163 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 133.130.121.141 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 160.16.113.133 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 45.77.20.103 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 133.243.238.243 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 45.76.218.37 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio ntpd[925]: 172.105.240.243 local addr 192.168.3.7 -> Jul 13 08:35:31 volumio sudo[8344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 13 08:35:31 volumio sudo[8344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:31 volumio sudo[8344]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:31 volumio volumio[8025]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 13 08:35:31 volumio sudo[8347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 13 08:35:31 volumio sudo[8347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:31 volumio sudo[8347]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:31 volumio volumio[8025]: info: Completed starting Core Plugins Jul 13 08:35:31 volumio volumio[8025]: info: ------------------------------------------- Jul 13 08:35:31 volumio volumio[8025]: info: ----- MyVolumio plugins startup ---- Jul 13 08:35:31 volumio volumio[8025]: info: ------------------------------------------- Jul 13 08:35:31 volumio volumio[8025]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 13 08:35:33 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 13 08:35:36 volumio wpa_supplicant[1118]: wlan0: Trying to associate with SSID 'A0957F891B71-2G' Jul 13 08:35:36 volumio dhcpcd[1143]: wlan0: carrier acquired Jul 13 08:35:36 volumio wpa_supplicant[1118]: wlan0: Associated with a0:95:7f:89:1b:70 Jul 13 08:35:36 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:95:7f:89:1b:70 completed [id=0 id_str=] Jul 13 08:35:36 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 13 08:35:36 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Jul 13 08:35:36 volumio dhcpcd[1143]: wlan0: IAID eb:ba:e8:23 Jul 13 08:35:36 volumio dhcpcd[1143]: wlan0: carrier lost Jul 13 08:35:37 volumio dhcpcd[1143]: wlan0: carrier acquired Jul 13 08:35:37 volumio dhcpcd[1143]: wlan0: IAID eb:ba:e8:23 Jul 13 08:35:37 volumio dhcpcd[1143]: wlan0: rebinding lease of 192.168.3.7 Jul 13 08:35:37 volumio dhcpcd[1143]: wlan0: soliciting an IPv6 router Jul 13 08:35:42 volumio dhcpcd[1143]: wlan0: probing for an IPv4LL address Jul 13 08:35:42 volumio dhcpcd[1143]: wlan0: DHCP lease expired Jul 13 08:35:42 volumio dhcpcd[1143]: wlan0: soliciting a DHCP lease Jul 13 08:35:42 volumio dhcpcd[1143]: wlan0: offered 192.168.3.7 from 192.168.3.1 Jul 13 08:35:42 volumio dhcpcd[1143]: wlan0: probing address 192.168.3.7/24 Jul 13 08:35:48 volumio dhcpcd[1143]: wlan0: leased 192.168.3.7 for 86400 seconds Jul 13 08:35:48 volumio avahi-daemon[487]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. Jul 13 08:35:48 volumio dhcpcd[1143]: wlan0: adding route to 192.168.3.0/24 Jul 13 08:35:48 volumio dhcpcd[1143]: wlan0: adding default route via 192.168.3.1 Jul 13 08:35:48 volumio avahi-daemon[487]: New relevant interface wlan0.IPv4 for mDNS. Jul 13 08:35:48 volumio avahi-daemon[487]: Registering new address record for 192.168.3.7 on wlan0.IPv4. Jul 13 08:35:49 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 08:35:49 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:49 volumio volumio[8025]: info: Discovery: adding 18bcd961-2cad-4825-9d0c-cefd458c8c21 Jul 13 08:35:49 volumio volumio[8025]: info: mDNS: Found device Volumio Jul 13 08:35:49 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:49 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:50 volumio volumio[8025]: info: [squeezelite_mc] 'client' notification received from volumio (192.168.3.7); type is 'disconnect' Jul 13 08:35:50 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:35:50 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:51 volumio ntpd[925]: Listen normally on 9 wlan0 192.168.3.7:123 Jul 13 08:35:51 volumio ntpd[925]: new interface(s) found: waking up resolver Jul 13 08:35:51 volumio volumio[8025]: error: MyVolumio Plugin failed to start in a timely fashion Jul 13 08:35:51 volumio volumio[8025]: info: BOOT COMPLETED Jul 13 08:35:51 volumio volumio[8025]: [Metrics] CommandRouter: 42s 644.33ms Jul 13 08:35:51 volumio volumio[8025]: info: CoreCommandRouter::volumiosetStartupVolume Jul 13 08:35:51 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 08:35:51 volumio volumio[8025]: info: VolumeController:: Setting startup Volume 80 Jul 13 08:35:51 volumio volumio[8025]: info: VolumeController::SetAlsaVolume80 Jul 13 08:35:51 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:51 volumio volumio[8025]: info: CoreCommandRouter::Close All Modals sent Jul 13 08:35:51 volumio volumio[8025]: info: CoreCommandRouter::Close All Modals sent Jul 13 08:35:51 volumio volumio[8025]: info: CoreStateMachine::pushState Jul 13 08:35:51 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:35:51 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 08:35:51 volumio volumio[8025]: info: CoreCommandRouter::volumioPushState Jul 13 08:35:51 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 13 08:35:52 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 13 08:35:52 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 13 08:35:52 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 13 08:35:54 volumio volumio[8025]: info: Adding plugin bluetooth to MyMusic Plugins Jul 13 08:35:54 volumio volumio[8025]: info: Adding plugin multiroom to MyMusic Plugins Jul 13 08:35:54 volumio volumio[8025]: info: Adding plugin metavolumio to MyMusic Plugins Jul 13 08:35:54 volumio volumio[8025]: info: Adding plugin cd_controller to MyMusic Plugins Jul 13 08:35:54 volumio volumio[8025]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 13 08:35:54 volumio volumio[8025]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 13 08:35:54 volumio volumio[8025]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 13 08:35:55 volumio volumio[8025]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 13 08:35:55 volumio volumio[8025]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 13 08:35:55 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:55 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:55 volumio volumio[8025]: info: Starting MyVolumio Remote Streaming Endpoints Jul 13 08:35:55 volumio volumio[8025]: info: MyVolumio login type: Token Jul 13 08:35:55 volumio volumio[8025]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 13 08:35:55 volumio volumio[8025]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 13 08:35:55 volumio volumio[8025]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 13 08:35:55 volumio volumio[8025]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 13 08:35:55 volumio volumio[8025]: info: Streaming services startup Jul 13 08:35:55 volumio volumio[8025]: info: Starting Streaming Daemon Jul 13 08:35:56 volumio sudo[8400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 13 08:35:56 volumio sudo[8400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:56 volumio systemd[1]: Stopping Volumio Streaming Daemon... Jul 13 08:35:56 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Jul 13 08:35:56 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Jul 13 08:35:56 volumio systemd[1]: Stopped Volumio Streaming Daemon. Jul 13 08:35:56 volumio systemd[1]: Started Volumio Streaming Daemon. Jul 13 08:35:56 volumio sudo[8400]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:56 volumio volumio-streaming-daemon[8406]: ############################ Jul 13 08:35:56 volumio volumio-streaming-daemon[8406]: # Volumio Streaming Daemon # Jul 13 08:35:56 volumio volumio-streaming-daemon[8406]: # Running on port 7777 # Jul 13 08:35:56 volumio volumio-streaming-daemon[8406]: ############################ Jul 13 08:35:56 volumio volumio-streaming-daemon[8406]: Environment: production Jul 13 08:35:57 volumio volumio[8025]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 13 08:35:57 volumio volumio[8025]: info: MYVOLUMIO: Adding device Jul 13 08:35:57 volumio volumio[8025]: info: MYVOLUMIO: Evaluating Server Jul 13 08:35:57 volumio volumio[8025]: info: MyVolumio status changed Jul 13 08:35:57 volumio volumio[8025]: info: Streaming services startup Jul 13 08:35:57 volumio volumio[8025]: info: Re-Starting Streaming Daemon Jul 13 08:35:57 volumio volumio[8025]: info: Removing browser output: myVolumio user plan is not superstar Jul 13 08:35:57 volumio volumio[8025]: info: Removing audio output: Jul 13 08:35:57 volumio volumio[8025]: info: Stoppping Tunnel 1 Jul 13 08:35:58 volumio volumio-streaming-daemon[8406]: Restarting daemon Jul 13 08:35:58 volumio volumio-streaming-daemon[8406]: Environment: production Jul 13 08:35:58 volumio volumio-streaming-daemon[8406]: Environment: production Jul 13 08:35:58 volumio volumio[8025]: info: Fetching Streaming Services browse cache Jul 13 08:35:58 volumio sudo[8416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 13 08:35:58 volumio sudo[8416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 08:35:58 volumio sudo[8416]: pam_unix(sudo:session): session closed for user root Jul 13 08:35:58 volumio volumio[8025]: info: Remote SSH Stopped Jul 13 08:35:58 volumio volumio-streaming-daemon[8406]: Environment: production Jul 13 08:35:58 volumio volumio[8025]: info: Fetching Streaming Services browse cache Jul 13 08:35:59 volumio volumio[8025]: info: Setting Geolocation for MyVolumio to as1 Jul 13 08:35:59 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:59 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:59 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:35:59 volumio volumio[8025]: info: [squeezelite_mc] 'client' notification received from volumio (192.168.3.7); type is 'connect' Jul 13 08:35:59 volumio volumio[8025]: info: [squeezelite_mc] Getting players connected to volumio (192.168.3.7) Jul 13 08:35:59 volumio volumio[8025]: info: [squeezelite_mc] Players connected to volumio (192.168.3.7): [{"id":"b8:27:eb:ba:e8:23","uuid":null,"ip":"192.168.3.7","name":"volumio","server":{"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"}}] Jul 13 08:35:59 volumio volumio[8025]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:ba:e8:23","uuid":null,"ip":"192.168.3.7","name":"volumio","server":{"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"}} Jul 13 08:36:00 volumio volumio[8025]: info: Updating MyVolumio device info Jul 13 08:36:00 volumio volumio[8025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 08:36:00 volumio volumio[8025]: info: CoreCommandRouter::volumioGetState Jul 13 08:36:00 volumio volumio[8025]: info: CorePlayQueue::getTrack 0 Jul 13 08:36:06 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:95:7f:89:1b:70 reason=0 locally_generated=1 Jul 13 08:36:06 volumio dhcpcd[1143]: wlan0: carrier lost Jul 13 08:36:06 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 13 08:36:06 volumio avahi-daemon[487]: Withdrawing address record for 192.168.3.7 on wlan0. Jul 13 08:36:06 volumio avahi-daemon[487]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. Jul 13 08:36:06 volumio avahi-daemon[487]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 13 08:36:06 volumio volumio[8025]: info: mDNS: A device disapperared from network Jul 13 08:36:06 volumio dhcpcd[1143]: wlan0: deleting route to 192.168.3.0/24 Jul 13 08:36:06 volumio dhcpcd[1143]: wlan0: deleting default route via 192.168.3.1 Jul 13 08:36:06 volumio volumio[8025]: info: mDNS: Device volumio disapperared from network Jul 13 08:36:07 volumio wpa_supplicant[1118]: wlan0: Trying to associate with SSID 'A0957F891B71-2G' Jul 13 08:36:08 volumio ntpd[925]: Soliciting pool server 2001:678:8::123 Jul 13 08:36:08 volumio ntpd[925]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 13 08:36:08 volumio dhcpcd[1143]: wlan0: carrier acquired Jul 13 08:36:08 volumio wpa_supplicant[1118]: wlan0: Associated with a0:95:7f:89:1b:70 Jul 13 08:36:08 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:95:7f:89:1b:70 completed [id=0 id_str=] Jul 13 08:36:08 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 13 08:36:08 volumio wpa_supplicant[1118]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Jul 13 08:36:08 volumio dhcpcd[1143]: wlan0: IAID eb:ba:e8:23 Jul 13 08:36:08 volumio dhcpcd[1143]: wlan0: carrier lost Jul 13 08:36:08 volumio dhcpcd[1143]: wlan0: carrier acquired Jul 13 08:36:08 volumio dhcpcd[1143]: wlan0: IAID eb:ba:e8:23 Jul 13 08:36:09 volumio dhcpcd[1143]: wlan0: soliciting an IPv6 router Jul 13 08:36:09 volumio dhcpcd[1143]: wlan0: rebinding lease of 192.168.3.7 Jul 13 08:36:11 volumio ntpd[925]: Deleting interface #9 wlan0, 192.168.3.7#123, interface stats: received=11, sent=11, dropped=0, active_time=20 secs Jul 13 08:36:11 volumio ntpd[925]: 202.181.103.212 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 202.182.111.234 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 172.105.204.105 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 45.76.221.157 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 133.243.238.163 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 133.130.121.141 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 160.16.113.133 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 45.77.20.103 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 133.243.238.243 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 45.76.218.37 local addr 192.168.3.7 -> Jul 13 08:36:11 volumio ntpd[925]: 172.105.240.243 local addr 192.168.3.7 -> Jul 13 08:36:12 volumio ntpd[925]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 13 08:36:14 volumio dhcpcd[1143]: wlan0: probing for an IPv4LL address Jul 13 08:36:14 volumio dhcpcd[1143]: wlan0: DHCP lease expired Jul 13 08:36:14 volumio dhcpcd[1143]: wlan0: soliciting a DHCP lease Jul 13 08:36:14 volumio dhcpcd[1143]: wlan0: offered 192.168.3.7 from 192.168.3.1 Jul 13 08:36:14 volumio dhcpcd[1143]: wlan0: probing address 192.168.3.7/24 Jul 13 08:36:19 volumio volumio[8025]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 08:36:19 volumio volumio[8025]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 13 08:36:19 volumio volumio[8025]: at doSend (dgram.js:692:16) Jul 13 08:36:19 volumio volumio[8025]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 13 08:36:19 volumio volumio[8025]: at afterDns (dgram.js:638:5) Jul 13 08:36:19 volumio volumio[8025]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 13 08:36:19 volumio volumio[8025]: errno: -101, Jul 13 08:36:19 volumio volumio[8025]: code: 'ENETUNREACH', Jul 13 08:36:19 volumio volumio[8025]: syscall: 'send', Jul 13 08:36:19 volumio volumio[8025]: address: '255.255.255.255', Jul 13 08:36:19 volumio volumio[8025]: port: 3483 Jul 13 08:36:19 volumio volumio[8025]: } Jul 13 08:36:19 volumio volumio[8025]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 08:36:20 volumio ntpd[925]: Soliciting pool server 150.249.188.191 Jul 13 08:36:20 volumio dhcpcd[1143]: wlan0: leased 192.168.3.7 for 86400 seconds Jul 13 08:36:21 volumio avahi-daemon[487]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. Jul 13 08:36:21 volumio avahi-daemon[487]: New relevant interface wlan0.IPv4 for mDNS. Jul 13 08:36:21 volumio dhcpcd[1143]: wlan0: adding route to 192.168.3.0/24 Jul 13 08:36:21 volumio avahi-daemon[487]: Registering new address record for 192.168.3.7 on wlan0.IPv4. Jul 13 08:36:21 volumio dhcpcd[1143]: wlan0: adding default route via 192.168.3.1 Jul 13 08:36:21 volumio sudo[8498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 08:35 Jul 13 08:36:21 volumio sudo[8498]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"