-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Tue 2024-11-26 14:26:13 JST. -- Nov 26 14:25:00 volumio3 ntfs-3g[1524]: Version 2017.3.23AR.3 integrated FUSE 28 Nov 26 14:25:00 volumio3 ntfs-3g[1524]: Mounted /dev/sda1 (Read-Write, label "My Passport", NTFS 3.1) Nov 26 14:25:00 volumio3 ntfs-3g[1524]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:25:00 volumio3 ntfs-3g[1524]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Nov 26 14:25:00 volumio3 ntfs-3g[1524]: Global ownership and permissions enforced, configuration type 7 Nov 26 14:25:00 volumio3 sudo[1515]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:00 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "volumio_command_line_client"... Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "upnp"... Nov 26 14:25:00 volumio3 volumio[1450]: info: [1732598700089] Starting Upmpd Daemon Nov 26 14:25:00 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "my_music"... Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "mpd"... Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "upnp_browser"... Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "alarm-clock"... Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "airplay_emulation"... Nov 26 14:25:00 volumio3 volumio[1450]: info: Starting Shairport Sync Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "last_100"... Nov 26 14:25:00 volumio3 volumio[1450]: info: Loading plugin "webradio"... Nov 26 14:25:01 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:01] [connect] Successful connection Nov 26 14:25:01 volumio3 volumio[1450]: info: Loading plugin "i2s_dacs"... Nov 26 14:25:01 volumio3 volumio[1450]: info: I2S DAC not set, start Auto-detection Nov 26 14:25:01 volumio3 volumio[1450]: info: Loading plugin "volumiodiscovery"... Nov 26 14:25:01 volumio3 volumio[1450]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:01 volumio3 volumio[1450]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:01 volumio3 volumio[1450]: *** WARNING *** For more information see Nov 26 14:25:01 volumio3 volumio[1450]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:01 volumio3 node[1450]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:01 volumio3 volumio[1450]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:01 volumio3 volumio[1450]: *** WARNING *** For more information see Nov 26 14:25:01 volumio3 node[1450]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:01 volumio3 node[1450]: *** WARNING *** For more information see Nov 26 14:25:01 volumio3 node[1450]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:01 volumio3 node[1450]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:01 volumio3 node[1450]: *** WARNING *** For more information see Nov 26 14:25:01 volumio3 volumio[1450]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 14:25:01 volumio3 volumio[1450]: info: Discovery: Started advertising with name: Volumio3 Nov 26 14:25:01 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:01 volumio3 volumio[1450]: info: Loading plugin "squeezelite_mc"... Nov 26 14:25:01 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:01 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:01 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:02 volumio3 volumio[1450]: info: Loading plugin "youtube2"... Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading plugin "outputs"... Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading plugin "albumart"... Nov 26 14:25:03 volumio3 volumio[1450]: info: Plugin example_plugin is not enabled Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading plugin "inputs"... Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading plugin "updater_comm"... Nov 26 14:25:03 volumio3 volumio[1450]: info: Plugin mpdemulation is not enabled Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading plugin "rest_api"... Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading plugin "websocket"... Nov 26 14:25:03 volumio3 volumio[1450]: info: Starting Socket.io Server version 2.3.0 Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading plugin "lms"... Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading plugin "minidlna"... Nov 26 14:25:03 volumio3 volumio[1450]: Forking 3 albumart workers Nov 26 14:25:03 volumio3 volumio[1450]: info: Applying required configuration parameters for plugin minidlna Nov 26 14:25:03 volumio3 volumio[1450]: info: Loading i18n strings for locale ja Nov 26 14:25:03 volumio3 volumio[1450]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:25:03 volumio3 volumio[1450]: Updating browse sources language Nov 26 14:25:03 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::initPlayerControls Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: Express server listening on port 3000 Nov 26 14:25:04 volumio3 volumio[1450]: [Metrics] WebUI: 11s 384.37ms Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreStateMachine::resetVolumioState Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreStateMachine::getcurrentVolume Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: Volumio Network Manager: Network status updated: 0 Nov 26 14:25:04 volumio3 volumio[1450]: info: Cannot mount NAS NEC3 at system boot, trial number 1 ,retrying in 5 seconds Nov 26 14:25:04 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732598701 101 Nov 26 14:25:04 volumio3 volumio[1450]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 26 14:25:04 volumio3 volumio[1450]: info: Reloading queue from file Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreStateMachine::setRepeat true single undefined Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreStateMachine::pushState Nov 26 14:25:04 volumio3 volumio[1450]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreStateMachine::setRandom false Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreStateMachine::pushState Nov 26 14:25:04 volumio3 volumio[1450]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:04 volumio3 volumio[1450]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreStateMachine::pushState Nov 26 14:25:04 volumio3 volumio[1450]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreStateMachine::updateTrackBlock Nov 26 14:25:04 volumio3 volumio[1450]: info: CorePlayQueue::getTrackBlock Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:04 volumio3 volumio[1450]: info: Setting Device type: Raspberry PI Nov 26 14:25:04 volumio3 volumio[1450]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 26 14:25:04 volumio3 volumio[1450]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Nov 26 14:25:04 volumio3 volumio[1450]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 26 14:25:04 volumio3 volumio[1450]: info: Completed loading Core Plugins Nov 26 14:25:04 volumio3 volumio[1450]: info: Preparing to generate the ALSA configuration file Nov 26 14:25:04 volumio3 volumio[1450]: Starting albumart workers Nov 26 14:25:04 volumio3 volumio[1450]: Starting albumart workers Nov 26 14:25:04 volumio3 volumio[1450]: info: Asound.conf file unchanged, so no further update is needed Nov 26 14:25:04 volumio3 volumio[1450]: info: Output device has changed, restarting MPD Nov 26 14:25:04 volumio3 volumio[1450]: Starting albumart workers Nov 26 14:25:04 volumio3 sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:25:04 volumio3 sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:04 volumio3 volumio[1450]: info: Output device has changed, restarting Shairport Sync Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:04 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:04 volumio3 sudo[1608]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:04 volumio3 sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:25:04 volumio3 sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:04 volumio3 systemd[1]: Stopping Music Player Daemon... Nov 26 14:25:05 volumio3 volumio[1450]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:05 volumio3 volumio[1450]: info: ___________ START PLUGINS ___________ Nov 26 14:25:05 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:25:05 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:25:05 volumio3 volumio[1450]: info: ControllerMpd::onStart: Initializing MPD Nov 26 14:25:05 volumio3 volumio[1450]: info: Creating MPD Configuration file Nov 26 14:25:05 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:25:05 volumio3 sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:25:05 volumio3 sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:05 volumio3 volumio[1450]: info: [1732598705137] CoreMusicLibrary::Adding element DLNAサーバー Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:05 volumio3 sudo[1618]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:05 volumio3 sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:25:05 volumio3 sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:05 volumio3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 26 14:25:05 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:25:05 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:25:05 volumio3 volumio[1450]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:05 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:25:05 volumio3 volumio[1450]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:05 volumio3 volumio[1450]: info: [1732598705427] CoreMusicLibrary::Adding element Last_100 Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:05 volumio3 sudo[1626]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 14:25:05 volumio3 sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:05 volumio3 volumio[1450]: info: [1732598705443] CoreMusicLibrary::Adding element Webradio Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:05 volumio3 sudo[1626]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:05 volumio3 volumio[1450]: info: Initializing BBC Radios Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:05 volumio3 volumio[1450]: info: [squeezelite_mc] Starting proxy server... Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:05 volumio3 volumio[1450]: info: [1732598705682] CoreMusicLibrary::Adding element YouTube2 Nov 26 14:25:05 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:05 volumio3 volumio[1450]: Cannot find translation for source YouTube2 Nov 26 14:25:05 volumio3 volumio[1450]: info: Loading i18n strings for locale ja Nov 26 14:25:05 volumio3 volumio[1450]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:25:05 volumio3 sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 14:25:05 volumio3 sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:05 volumio3 volumio[1450]: info: Volumio Calling Home Nov 26 14:25:05 volumio3 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 14:25:05 volumio3 squeezeboxserver[1386]: [24-11-26 14:24:59.9171] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:25:05 volumio3 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 14:25:05 volumio3 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 14:25:06 volumio3 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 14:25:06 volumio3 volumio[1450]: info: [squeezelite_mc] Proxy server started on port 44067 Nov 26 14:25:06 volumio3 systemd[1]: Started Logitech Media Server Daemon. Nov 26 14:25:06 volumio3 sudo[1659]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:06 volumio3 volumio[1450]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:06 volumio3 volumio[1450]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 14:25:06 volumio3 volumio[1450]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 14:25:06 volumio3 volumio[1450]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 14:25:06 volumio3 volumio[1450]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 14:25:06 volumio3 volumio[1450]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 14:25:06 volumio3 volumio[1450]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 14:25:06 volumio3 volumio[1450]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 14:25:06 volumio3 volumio[1450]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 14:25:06 volumio3 volumio[1450]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 14:25:06 volumio3 volumio[1450]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 14:25:06 volumio3 volumio[1450]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 14:25:06 volumio3 volumio[1450]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 14:25:06 volumio3 volumio[1450]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 14:25:06 volumio3 volumio[1450]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 14:25:06 volumio3 volumio[1450]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 14:25:06 volumio3 volumio[1450]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 14:25:06 volumio3 volumio[1450]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 14:25:06 volumio3 volumio[1450]: info: minidlna: Found miniDLNA version 1.2.1 Nov 26 14:25:06 volumio3 volumio[1450]: info: minidlna: Starting minidlna.service Nov 26 14:25:06 volumio3 volumio[1450]: info: logitechmediaserver started Nov 26 14:25:06 volumio3 volumio[1450]: info: MPD Permissions set Nov 26 14:25:06 volumio3 volumio[1450]: info: MPD Permissions set Nov 26 14:25:06 volumio3 volumio[1450]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:06 volumio3 volumio[1450]: info: CoreStateMachine::pushState Nov 26 14:25:06 volumio3 volumio[1450]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:06 volumio3 volumio[1450]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:06 volumio3 sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Nov 26 14:25:06 volumio3 sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:06 volumio3 volumio[1450]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:06 volumio3 volumio[1450]: info: CoreStateMachine::pushState Nov 26 14:25:06 volumio3 volumio[1450]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:06 volumio3 volumio[1450]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:06 volumio3 volumio[1450]: info: [squeezelite_mc] Server discovery started Nov 26 14:25:06 volumio3 volumio[1450]: info: [squeezelite_mc] Player finder started Nov 26 14:25:06 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:06 volumio3 volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:06 volumio3 volumio[1450]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 14:25:06 volumio3 volumio[1450]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:25:06 volumio3 volumio[1450]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 14:25:06 volumio3 volumio[1450]: at doSend (dgram.js:692:16) Nov 26 14:25:06 volumio3 volumio[1450]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 26 14:25:06 volumio3 volumio[1450]: at afterDns (dgram.js:638:5) Nov 26 14:25:06 volumio3 volumio[1450]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 26 14:25:06 volumio3 volumio[1450]: errno: -101, Nov 26 14:25:06 volumio3 volumio[1450]: code: 'ENETUNREACH', Nov 26 14:25:06 volumio3 volumio[1450]: syscall: 'send', Nov 26 14:25:06 volumio3 volumio[1450]: address: '255.255.255.255', Nov 26 14:25:06 volumio3 volumio[1450]: port: 3483 Nov 26 14:25:06 volumio3 volumio[1450]: } Nov 26 14:25:06 volumio3 volumio[1450]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:25:06 volumio3 systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Nov 26 14:25:06 volumio3 sudo[1694]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:06 volumio3 systemd[1]: minidlna.service: Succeeded. Nov 26 14:25:06 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:06 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:06 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:07 volumio3 sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 14:24 Nov 26 14:25:07 volumio3 sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:07 volumio3 sudo[1714]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:07 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 26 14:25:07 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 26 14:25:07 volumio3 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 26 14:25:07 volumio3 ntfs-3g[1524]: Unmounting /dev/sda1 (My Passport) Nov 26 14:25:07 volumio3 mpd[1631]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 14:25:07 volumio3 mpd[1631]: output: No 'audio_output' defined in config file Nov 26 14:25:07 volumio3 sudo[1620]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:07 volumio3 systemd[1]: media-My_Passport.mount: Succeeded. Nov 26 14:25:07 volumio3 sudo[1611]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:07 volumio3 mpd[1631]: output: Successfully detected a sndio audio device Nov 26 14:25:07 volumio3 mpd[1631]: zeroconf: No global port, disabling zeroconf Nov 26 14:25:07 volumio3 systemd[1]: Started Music Player Daemon. Nov 26 14:25:07 volumio3 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 26 14:25:07 volumio3 systemd[1]: Started dynamicswap service. Nov 26 14:25:07 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 26 14:25:07 volumio3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 26 14:25:07 volumio3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Nov 26 14:25:07 volumio3 systemd[1]: Started dynamicswap service. Nov 26 14:25:07 volumio3 systemd[1]: Stopped Volumio Backend Module. Nov 26 14:25:07 volumio3 systemd[1]: Started Volumio Backend Module. Nov 26 14:25:07 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 26 14:25:09 volumio3 ntpd[855]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 26 14:25:09 volumio3 volumio[1732]: info: ------------------------------------------- Nov 26 14:25:09 volumio3 volumio[1732]: info: ----- Volumio3 ---- Nov 26 14:25:09 volumio3 volumio[1732]: info: ------------------------------------------- Nov 26 14:25:09 volumio3 volumio[1732]: info: ----- System startup ---- Nov 26 14:25:09 volumio3 volumio[1732]: info: ------------------------------------------- Nov 26 14:25:10 volumio3 volumio[1732]: info: MYVOLUMIO Environment detected Nov 26 14:25:10 volumio3 volumio[1732]: info: Plugin folders cleanup Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning into folder /volumio/app/plugins/ Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning category audio_interface Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning category miscellanea Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning category music_service Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning category plugins.json Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning category system_controller Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning category user_interface Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning into folder /data/plugins/ Nov 26 14:25:10 volumio3 volumio[1732]: info: Scanning category music_service Nov 26 14:25:10 volumio3 volumio[1732]: info: Plugin folders cleanup completed Nov 26 14:25:10 volumio3 volumio[1732]: info: ------------------------------------------- Nov 26 14:25:10 volumio3 volumio[1732]: info: ----- Core plugins startup ---- Nov 26 14:25:10 volumio3 volumio[1732]: info: ------------------------------------------- Nov 26 14:25:10 volumio3 volumio[1732]: info: Loading plugins from folder /volumio/app/plugins/ Nov 26 14:25:10 volumio3 volumio[1732]: info: Adding plugin upnp to MyMusic Plugins Nov 26 14:25:10 volumio3 volumio[1732]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 26 14:25:10 volumio3 volumio[1732]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 26 14:25:10 volumio3 volumio[1732]: info: Loading plugins from folder /data/plugins/ Nov 26 14:25:10 volumio3 volumio[1732]: info: Loading plugin "system"... Nov 26 14:25:10 volumio3 volumio[1732]: info: Loading plugin "appearance"... Nov 26 14:25:11 volumio3 ntpd[855]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 26 14:25:11 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:11 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:11 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:12 volumio3 volumio[1732]: info: Loading plugin "network"... Nov 26 14:25:12 volumio3 volumio[1732]: info: Refreshing Cached IP Addresses Nov 26 14:25:12 volumio3 sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 14:25:12 volumio3 sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:12 volumio3 sudo[1766]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:12 volumio3 volumio[1732]: info: Loading plugin "services"... Nov 26 14:25:12 volumio3 sudo[1768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 14:25:12 volumio3 volumio[1732]: info: Loading plugin "alsa_controller"... Nov 26 14:25:12 volumio3 sudo[1768]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:12 volumio3 sudo[1768]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:12 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:12 volumio3 volumio[1732]: info: Loading plugin "wizard"... Nov 26 14:25:12 volumio3 volumio[1732]: info: Loading plugin "networkfs"... Nov 26 14:25:12 volumio3 volumio[1732]: info: Starting Udev Watcher for removable devices Nov 26 14:25:12 volumio3 sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kenshi,password=dke,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.20/share\\音楽 /mnt/NAS/NEC3 Nov 26 14:25:12 volumio3 sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:12 volumio3 sudo[1791]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:12 volumio3 kernel: CIFS: Attempting to mount //192.168.2.20/share/音楽 Nov 26 14:25:12 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 26 14:25:12 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Nov 26 14:25:12 volumio3 volumio[1732]: info: Ignoring mount for partition: boot Nov 26 14:25:12 volumio3 volumio[1732]: info: Ignoring mount for partition: volumio Nov 26 14:25:12 volumio3 volumio[1732]: info: Ignoring mount for partition: volumio_data Nov 26 14:25:12 volumio3 volumio[1732]: info: Mounting Device My_Passport Nov 26 14:25:12 volumio3 sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/My_Passport -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:25:12 volumio3 sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:12 volumio3 ntpd[855]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 26 14:25:12 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:12] [connect] Successful connection Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:09.9739] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:10.6107] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6498] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6506] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6512] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6573] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6598] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6615] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6645] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6730] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.6765] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 26 14:25:12 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:12.9380] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. Nov 26 14:25:14 volumio3 ntpd[855]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 26 14:25:15 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:15.0112] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.0678] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (System error) Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1217] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1224] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.001 seconds. Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1250] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1313] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (System error) Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1326] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1335] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.001 seconds. Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1346] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1371] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1377] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.1410] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2524] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2547] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2568] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2661] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2812] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2823] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.148 seconds. Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2836] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2862] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.002 seconds. Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2892] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2899] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.002 seconds. Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.2912] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Nov 26 14:25:16 volumio3 ntfs-3g[1803]: Version 2017.3.23AR.3 integrated FUSE 28 Nov 26 14:25:16 volumio3 ntfs-3g[1803]: Mounted /dev/sda1 (Read-Write, label "My Passport", NTFS 3.1) Nov 26 14:25:16 volumio3 ntfs-3g[1803]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:25:16 volumio3 ntfs-3g[1803]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Nov 26 14:25:16 volumio3 ntfs-3g[1803]: Global ownership and permissions enforced, configuration type 7 Nov 26 14:25:16 volumio3 sudo[1799]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:16 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:16 volumio3 volumio[1732]: info: Loading plugin "volumio_command_line_client"... Nov 26 14:25:16 volumio3 volumio[1732]: info: Loading plugin "upnp"... Nov 26 14:25:16 volumio3 volumio[1732]: info: [1732598716382] Starting Upmpd Daemon Nov 26 14:25:16 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:16 volumio3 volumio[1732]: info: Loading plugin "my_music"... Nov 26 14:25:16 volumio3 volumio[1732]: info: Loading plugin "mpd"... Nov 26 14:25:16 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.4156] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.123 seconds. Nov 26 14:25:16 volumio3 volumio[1732]: info: Loading plugin "upnp_browser"... Nov 26 14:25:16 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:16 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:16 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:17 volumio3 volumio[1732]: info: Loading plugin "alarm-clock"... Nov 26 14:25:17 volumio3 volumio[1732]: info: Loading plugin "airplay_emulation"... Nov 26 14:25:17 volumio3 volumio[1732]: info: Starting Shairport Sync Nov 26 14:25:17 volumio3 volumio[1732]: info: Loading plugin "last_100"... Nov 26 14:25:17 volumio3 volumio[1732]: info: Loading plugin "webradio"... Nov 26 14:25:17 volumio3 volumio[1732]: info: Loading plugin "i2s_dacs"... Nov 26 14:25:17 volumio3 volumio[1732]: info: I2S DAC not set, start Auto-detection Nov 26 14:25:17 volumio3 volumio[1732]: info: Loading plugin "volumiodiscovery"... Nov 26 14:25:17 volumio3 volumio[1732]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:17 volumio3 volumio[1732]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:17 volumio3 volumio[1732]: *** WARNING *** For more information see Nov 26 14:25:17 volumio3 volumio[1732]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:17 volumio3 node[1732]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:17 volumio3 volumio[1732]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:17 volumio3 volumio[1732]: *** WARNING *** For more information see Nov 26 14:25:17 volumio3 node[1732]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:17 volumio3 node[1732]: *** WARNING *** For more information see Nov 26 14:25:17 volumio3 node[1732]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:17 volumio3 node[1732]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:17 volumio3 node[1732]: *** WARNING *** For more information see Nov 26 14:25:17 volumio3 volumio[1732]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 14:25:17 volumio3 volumio[1732]: info: Discovery: Started advertising with name: Volumio3 Nov 26 14:25:17 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:17 volumio3 volumio[1732]: info: Loading plugin "squeezelite_mc"... Nov 26 14:25:18 volumio3 volumio[1732]: info: Loading plugin "youtube2"... Nov 26 14:25:19 volumio3 volumio[1732]: info: Loading plugin "outputs"... Nov 26 14:25:19 volumio3 volumio[1732]: info: Loading plugin "albumart"... Nov 26 14:25:19 volumio3 volumio[1732]: info: Plugin example_plugin is not enabled Nov 26 14:25:19 volumio3 volumio[1732]: info: Loading plugin "inputs"... Nov 26 14:25:19 volumio3 volumio[1732]: info: Loading plugin "updater_comm"... Nov 26 14:25:19 volumio3 volumio[1732]: info: Plugin mpdemulation is not enabled Nov 26 14:25:19 volumio3 volumio[1732]: info: Loading plugin "rest_api"... Nov 26 14:25:19 volumio3 volumio[1732]: info: Loading plugin "websocket"... Nov 26 14:25:19 volumio3 volumio[1732]: info: Starting Socket.io Server version 2.3.0 Nov 26 14:25:19 volumio3 volumio[1732]: info: Loading plugin "lms"... Nov 26 14:25:19 volumio3 volumio[1732]: info: Loading plugin "minidlna"... Nov 26 14:25:19 volumio3 volumio[1732]: Forking 3 albumart workers Nov 26 14:25:20 volumio3 volumio[1732]: info: Applying required configuration parameters for plugin minidlna Nov 26 14:25:20 volumio3 volumio[1732]: info: Loading i18n strings for locale ja Nov 26 14:25:20 volumio3 volumio[1732]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:25:20 volumio3 volumio[1732]: Updating browse sources language Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::initPlayerControls Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: Express server listening on port 3000 Nov 26 14:25:20 volumio3 volumio[1732]: [Metrics] WebUI: 11s 227.32ms Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreStateMachine::resetVolumioState Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreStateMachine::getcurrentVolume Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:20 volumio3 volumio[1732]: info: Volumio Network Manager: Network status updated: 0 Nov 26 14:25:20 volumio3 volumio[1732]: info: Cannot mount NAS NEC3 at system boot, trial number 1 ,retrying in 5 seconds Nov 26 14:25:20 volumio3 volumio[1732]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreStateMachine::pushState Nov 26 14:25:20 volumio3 volumio[1732]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreStateMachine::updateTrackBlock Nov 26 14:25:20 volumio3 volumio[1732]: info: CorePlayQueue::getTrackBlock Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:20 volumio3 volumio[1732]: info: Reloading queue from file Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreStateMachine::setRepeat true single undefined Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreStateMachine::pushState Nov 26 14:25:20 volumio3 volumio[1732]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreStateMachine::setRandom false Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreStateMachine::pushState Nov 26 14:25:20 volumio3 volumio[1732]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:20 volumio3 volumio[1732]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:20 volumio3 volumio[1732]: info: Setting Device type: Raspberry PI Nov 26 14:25:20 volumio3 volumio[1732]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 26 14:25:20 volumio3 volumio[1732]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Nov 26 14:25:20 volumio3 volumio[1732]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 26 14:25:21 volumio3 volumio[1732]: Starting albumart workers Nov 26 14:25:21 volumio3 volumio[1732]: info: Completed loading Core Plugins Nov 26 14:25:21 volumio3 volumio[1732]: info: Preparing to generate the ALSA configuration file Nov 26 14:25:21 volumio3 volumio[1732]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreStateMachine::pushState Nov 26 14:25:21 volumio3 volumio[1732]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:21 volumio3 volumio[1732]: Starting albumart workers Nov 26 14:25:21 volumio3 volumio[1732]: info: Asound.conf file unchanged, so no further update is needed Nov 26 14:25:21 volumio3 volumio[1732]: info: Output device has changed, restarting MPD Nov 26 14:25:21 volumio3 volumio[1732]: Starting albumart workers Nov 26 14:25:21 volumio3 volumio[1732]: info: Output device has changed, restarting Shairport Sync Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:21 volumio3 sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:25:21 volumio3 sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:21 volumio3 sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:25:21 volumio3 sudo[1852]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:21 volumio3 sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:21 volumio3 volumio[1732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:21 volumio3 volumio[1732]: info: ___________ START PLUGINS ___________ Nov 26 14:25:21 volumio3 systemd[1]: Stopping Music Player Daemon... Nov 26 14:25:21 volumio3 volumio[1732]: info: ControllerMpd::onStart: Initializing MPD Nov 26 14:25:21 volumio3 volumio[1732]: info: Creating MPD Configuration file Nov 26 14:25:21 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:25:21 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:21 volumio3 volumio[1732]: info: [1732598721275] CoreMusicLibrary::Adding element DLNAサーバー Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:21 volumio3 sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:25:21 volumio3 sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:21 volumio3 sudo[1865]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:21 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:25:21 volumio3 sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:25:21 volumio3 sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:21 volumio3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 26 14:25:21 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:25:21 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:25:21 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:25:21 volumio3 volumio[1732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:21 volumio3 sudo[1873]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 14:25:21 volumio3 sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:21 volumio3 sudo[1873]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:21 volumio3 volumio[1732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:21 volumio3 volumio[1732]: info: [1732598721577] CoreMusicLibrary::Adding element Last_100 Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:21 volumio3 volumio[1732]: info: [1732598721580] CoreMusicLibrary::Adding element Webradio Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:21 volumio3 volumio[1732]: info: Initializing BBC Radios Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:21 volumio3 volumio[1732]: info: [squeezelite_mc] Starting proxy server... Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:21 volumio3 volumio[1732]: info: [1732598721915] CoreMusicLibrary::Adding element YouTube2 Nov 26 14:25:21 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:21 volumio3 volumio[1732]: Cannot find translation for source YouTube2 Nov 26 14:25:21 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:21 volumio3 volumio[1732]: info: Loading i18n strings for locale ja Nov 26 14:25:21 volumio3 volumio[1732]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:25:22 volumio3 sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 14:25:22 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:22 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:22 volumio3 sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:22 volumio3 volumio[1732]: info: Volumio Calling Home Nov 26 14:25:22 volumio3 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 14:25:22 volumio3 squeezeboxserver[1690]: [24-11-26 14:25:16.6094] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:25:22 volumio3 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 14:25:22 volumio3 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 14:25:22 volumio3 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 14:25:22 volumio3 systemd[1]: Started Logitech Media Server Daemon. Nov 26 14:25:22 volumio3 sudo[1906]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:22 volumio3 volumio[1732]: info: [squeezelite_mc] Proxy server started on port 43011 Nov 26 14:25:22 volumio3 volumio[1732]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:22 volumio3 volumio[1732]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 14:25:22 volumio3 volumio[1732]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 14:25:22 volumio3 volumio[1732]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 14:25:22 volumio3 volumio[1732]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 14:25:22 volumio3 volumio[1732]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 14:25:22 volumio3 volumio[1732]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 14:25:22 volumio3 volumio[1732]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 14:25:22 volumio3 volumio[1732]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 14:25:22 volumio3 volumio[1732]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 14:25:22 volumio3 volumio[1732]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 14:25:22 volumio3 volumio[1732]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 14:25:22 volumio3 volumio[1732]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 14:25:22 volumio3 volumio[1732]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 14:25:22 volumio3 volumio[1732]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 14:25:22 volumio3 volumio[1732]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 14:25:22 volumio3 volumio[1732]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 14:25:22 volumio3 volumio[1732]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 14:25:22 volumio3 volumio[1732]: info: minidlna: Found miniDLNA version 1.2.1 Nov 26 14:25:22 volumio3 volumio[1732]: info: minidlna: Starting minidlna.service Nov 26 14:25:22 volumio3 volumio[1732]: info: logitechmediaserver started Nov 26 14:25:22 volumio3 volumio[1732]: info: MPD Permissions set Nov 26 14:25:22 volumio3 volumio[1732]: info: MPD Permissions set Nov 26 14:25:22 volumio3 volumio[1732]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:22 volumio3 sudo[1946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Nov 26 14:25:22 volumio3 volumio[1732]: info: CoreStateMachine::pushState Nov 26 14:25:22 volumio3 sudo[1946]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:22 volumio3 volumio[1732]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:22 volumio3 volumio[1732]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:22 volumio3 volumio[1732]: info: [squeezelite_mc] Server discovery started Nov 26 14:25:22 volumio3 volumio[1732]: info: [squeezelite_mc] Player finder started Nov 26 14:25:22 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:22 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:22 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:22 volumio3 volumio[1732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:22 volumio3 volumio[1732]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 14:25:22 volumio3 volumio[1732]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:25:22 volumio3 volumio[1732]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 14:25:22 volumio3 volumio[1732]: at doSend (dgram.js:692:16) Nov 26 14:25:22 volumio3 volumio[1732]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 26 14:25:22 volumio3 volumio[1732]: at afterDns (dgram.js:638:5) Nov 26 14:25:22 volumio3 volumio[1732]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 26 14:25:22 volumio3 volumio[1732]: errno: -101, Nov 26 14:25:22 volumio3 volumio[1732]: code: 'ENETUNREACH', Nov 26 14:25:22 volumio3 volumio[1732]: syscall: 'send', Nov 26 14:25:22 volumio3 volumio[1732]: address: '255.255.255.255', Nov 26 14:25:22 volumio3 volumio[1732]: port: 3483 Nov 26 14:25:22 volumio3 volumio[1732]: } Nov 26 14:25:22 volumio3 volumio[1732]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:25:22 volumio3 systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Nov 26 14:25:22 volumio3 sudo[1946]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:23 volumio3 systemd[1]: minidlna.service: Succeeded. Nov 26 14:25:23 volumio3 sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 14:24 Nov 26 14:25:23 volumio3 sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:23 volumio3 mpd[1878]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 14:25:23 volumio3 mpd[1878]: output: No 'audio_output' defined in config file Nov 26 14:25:23 volumio3 sudo[1961]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:23 volumio3 mpd[1878]: output: Successfully detected a sndio audio device Nov 26 14:25:23 volumio3 mpd[1878]: zeroconf: No global port, disabling zeroconf Nov 26 14:25:23 volumio3 systemd[1]: Started Music Player Daemon. Nov 26 14:25:23 volumio3 sudo[1867]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:23 volumio3 sudo[1856]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:23 volumio3 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 26 14:25:23 volumio3 ntfs-3g[1803]: Unmounting /dev/sda1 (My Passport) Nov 26 14:25:23 volumio3 systemd[1]: media-My_Passport.mount: Succeeded. Nov 26 14:25:23 volumio3 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 26 14:25:23 volumio3 systemd[1]: Started dynamicswap service. Nov 26 14:25:23 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 26 14:25:23 volumio3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 26 14:25:23 volumio3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Nov 26 14:25:23 volumio3 systemd[1]: Started dynamicswap service. Nov 26 14:25:23 volumio3 systemd[1]: Stopped Volumio Backend Module. Nov 26 14:25:23 volumio3 systemd[1]: Started Volumio Backend Module. Nov 26 14:25:23 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 26 14:25:25 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:25] [info] asio async_connect error: system:111 (Connection refused) Nov 26 14:25:25 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 26 14:25:25 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:25] [error] handle_connect error: Underlying Transport Error Nov 26 14:25:26 volumio3 volumio[1979]: info: ------------------------------------------- Nov 26 14:25:26 volumio3 volumio[1979]: info: ----- Volumio3 ---- Nov 26 14:25:26 volumio3 volumio[1979]: info: ------------------------------------------- Nov 26 14:25:26 volumio3 volumio[1979]: info: ----- System startup ---- Nov 26 14:25:26 volumio3 volumio[1979]: info: ------------------------------------------- Nov 26 14:25:26 volumio3 volumio[1979]: info: MYVOLUMIO Environment detected Nov 26 14:25:26 volumio3 volumio[1979]: info: Plugin folders cleanup Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning into folder /volumio/app/plugins/ Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning category audio_interface Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning category miscellanea Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning category music_service Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning category plugins.json Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning category system_controller Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning category user_interface Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning into folder /data/plugins/ Nov 26 14:25:26 volumio3 volumio[1979]: info: Scanning category music_service Nov 26 14:25:26 volumio3 volumio[1979]: info: Plugin folders cleanup completed Nov 26 14:25:26 volumio3 volumio[1979]: info: ------------------------------------------- Nov 26 14:25:26 volumio3 volumio[1979]: info: ----- Core plugins startup ---- Nov 26 14:25:26 volumio3 volumio[1979]: info: ------------------------------------------- Nov 26 14:25:26 volumio3 volumio[1979]: info: Loading plugins from folder /volumio/app/plugins/ Nov 26 14:25:26 volumio3 volumio[1979]: info: Adding plugin upnp to MyMusic Plugins Nov 26 14:25:26 volumio3 volumio[1979]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 26 14:25:26 volumio3 volumio[1979]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 26 14:25:26 volumio3 volumio[1979]: info: Loading plugins from folder /data/plugins/ Nov 26 14:25:27 volumio3 volumio[1979]: info: Loading plugin "system"... Nov 26 14:25:27 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:27 volumio3 volumio[1979]: info: Loading plugin "appearance"... Nov 26 14:25:27 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:27 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:28 volumio3 volumio[1979]: info: Loading plugin "network"... Nov 26 14:25:28 volumio3 volumio[1979]: info: Refreshing Cached IP Addresses Nov 26 14:25:28 volumio3 sudo[2010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 14:25:28 volumio3 sudo[2010]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:28 volumio3 volumio[1979]: info: Loading plugin "services"... Nov 26 14:25:28 volumio3 sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 14:25:28 volumio3 sudo[2012]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:28 volumio3 sudo[2010]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:28 volumio3 volumio[1979]: info: Loading plugin "alsa_controller"... Nov 26 14:25:28 volumio3 sudo[2012]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:28 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:28 volumio3 volumio[1979]: info: Loading plugin "wizard"... Nov 26 14:25:28 volumio3 volumio[1979]: info: Loading plugin "networkfs"... Nov 26 14:25:28 volumio3 volumio[1979]: info: Starting Udev Watcher for removable devices Nov 26 14:25:28 volumio3 sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kenshi,password=dke,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.20/share\\音楽 /mnt/NAS/NEC3 Nov 26 14:25:28 volumio3 sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:28 volumio3 kernel: CIFS: Attempting to mount //192.168.2.20/share/音楽 Nov 26 14:25:28 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 26 14:25:28 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Nov 26 14:25:28 volumio3 sudo[2035]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:28 volumio3 volumio[1979]: info: Ignoring mount for partition: boot Nov 26 14:25:28 volumio3 volumio[1979]: info: Ignoring mount for partition: volumio Nov 26 14:25:28 volumio3 volumio[1979]: info: Ignoring mount for partition: volumio_data Nov 26 14:25:28 volumio3 volumio[1979]: info: Mounting Device My_Passport Nov 26 14:25:28 volumio3 sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/My_Passport -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:25:28 volumio3 sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:26.2750] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:26.9148] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9152] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9161] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9169] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9230] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9256] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9273] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9303] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9389] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:28.9425] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 26 14:25:29 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:29.2045] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. Nov 26 14:25:31 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:31.4062] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 Nov 26 14:25:32 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:32 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:32 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:32 volumio3 ntfs-3g[2055]: Version 2017.3.23AR.3 integrated FUSE 28 Nov 26 14:25:32 volumio3 ntfs-3g[2055]: Mounted /dev/sda1 (Read-Write, label "My Passport", NTFS 3.1) Nov 26 14:25:32 volumio3 ntfs-3g[2055]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:25:32 volumio3 ntfs-3g[2055]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Nov 26 14:25:32 volumio3 ntfs-3g[2055]: Global ownership and permissions enforced, configuration type 7 Nov 26 14:25:32 volumio3 sudo[2043]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:32 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:32 volumio3 volumio[1979]: info: Loading plugin "volumio_command_line_client"... Nov 26 14:25:32 volumio3 volumio[1979]: info: Loading plugin "upnp"... Nov 26 14:25:32 volumio3 volumio[1979]: info: [1732598732237] Starting Upmpd Daemon Nov 26 14:25:32 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:32 volumio3 volumio[1979]: info: Loading plugin "my_music"... Nov 26 14:25:32 volumio3 volumio[1979]: info: Loading plugin "mpd"... Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.5451] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (System error) Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6436] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6449] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.001 seconds. Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6479] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Nov 26 14:25:32 volumio3 volumio[1979]: info: Loading plugin "upnp_browser"... Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6559] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (System error) Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6576] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6588] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.001 seconds. Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6603] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6634] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6645] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.6688] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.7937] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.7964] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.7987] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.8089] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.8261] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.8275] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.167 seconds. Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.8291] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.8320] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.003 seconds. Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.8354] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.8364] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.002 seconds. Nov 26 14:25:32 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:32.8381] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Nov 26 14:25:32 volumio3 volumio[1979]: info: Loading plugin "alarm-clock"... Nov 26 14:25:33 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:33.0034] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.164 seconds. Nov 26 14:25:33 volumio3 systemd[1]: nmbd.service: Start operation timed out. Terminating. Nov 26 14:25:33 volumio3 systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Nov 26 14:25:33 volumio3 systemd[1]: nmbd.service: Failed with result 'timeout'. Nov 26 14:25:33 volumio3 systemd[1]: Failed to start Samba NMB Daemon. Nov 26 14:25:33 volumio3 systemd[1]: Starting Samba Winbind Daemon... Nov 26 14:25:33 volumio3 volumio[1979]: info: Loading plugin "airplay_emulation"... Nov 26 14:25:33 volumio3 volumio[1979]: info: Starting Shairport Sync Nov 26 14:25:33 volumio3 volumio[1979]: info: Loading plugin "last_100"... Nov 26 14:25:33 volumio3 volumio[1979]: info: Loading plugin "webradio"... Nov 26 14:25:33 volumio3 volumio[1979]: info: Loading plugin "i2s_dacs"... Nov 26 14:25:33 volumio3 volumio[1979]: info: I2S DAC not set, start Auto-detection Nov 26 14:25:33 volumio3 volumio[1979]: info: Loading plugin "volumiodiscovery"... Nov 26 14:25:33 volumio3 volumio[1979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:33 volumio3 volumio[1979]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:33 volumio3 volumio[1979]: *** WARNING *** For more information see Nov 26 14:25:33 volumio3 volumio[1979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:33 volumio3 volumio[1979]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:33 volumio3 node[1979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:33 volumio3 volumio[1979]: *** WARNING *** For more information see Nov 26 14:25:33 volumio3 node[1979]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:33 volumio3 node[1979]: *** WARNING *** For more information see Nov 26 14:25:33 volumio3 node[1979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:33 volumio3 node[1979]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:33 volumio3 node[1979]: *** WARNING *** For more information see Nov 26 14:25:33 volumio3 volumio[1979]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 14:25:33 volumio3 volumio[1979]: info: Discovery: Started advertising with name: Volumio3 Nov 26 14:25:33 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:33 volumio3 volumio[1979]: info: Loading plugin "squeezelite_mc"... Nov 26 14:25:33 volumio3 winbindd[2057]: [2024/11/26 14:25:33.520492, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 26 14:25:33 volumio3 winbindd[2057]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 26 14:25:33 volumio3 winbindd[2057]: [2024/11/26 14:25:33.540512, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 26 14:25:33 volumio3 systemd[1]: Started Samba Winbind Daemon. Nov 26 14:25:33 volumio3 winbindd[2057]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 26 14:25:33 volumio3 systemd[1]: Starting Samba SMB Daemon... Nov 26 14:25:34 volumio3 systemd[1]: Started Samba SMB Daemon. Nov 26 14:25:34 volumio3 smbd[2063]: [2024/11/26 14:25:34.149822, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 26 14:25:34 volumio3 smbd[2063]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 26 14:25:34 volumio3 volumio[1979]: info: Loading plugin "youtube2"... Nov 26 14:25:35 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:35] [connect] Successful connection Nov 26 14:25:35 volumio3 volumio[1979]: info: Loading plugin "outputs"... Nov 26 14:25:35 volumio3 volumio[1979]: info: Loading plugin "albumart"... Nov 26 14:25:35 volumio3 volumio[1979]: info: Plugin example_plugin is not enabled Nov 26 14:25:35 volumio3 volumio[1979]: info: Loading plugin "inputs"... Nov 26 14:25:35 volumio3 volumio[1979]: info: Loading plugin "updater_comm"... Nov 26 14:25:35 volumio3 volumio[1979]: info: Plugin mpdemulation is not enabled Nov 26 14:25:35 volumio3 volumio[1979]: info: Loading plugin "rest_api"... Nov 26 14:25:35 volumio3 volumio[1979]: info: Loading plugin "websocket"... Nov 26 14:25:35 volumio3 volumio[1979]: info: Starting Socket.io Server version 2.3.0 Nov 26 14:25:35 volumio3 volumio[1979]: info: Loading plugin "lms"... Nov 26 14:25:36 volumio3 volumio[1979]: info: Loading plugin "minidlna"... Nov 26 14:25:36 volumio3 volumio[1979]: Forking 3 albumart workers Nov 26 14:25:36 volumio3 volumio[1979]: info: Applying required configuration parameters for plugin minidlna Nov 26 14:25:36 volumio3 volumio[1979]: info: Loading i18n strings for locale ja Nov 26 14:25:36 volumio3 volumio[1979]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:25:36 volumio3 volumio[1979]: Updating browse sources language Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::initPlayerControls Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: Express server listening on port 3000 Nov 26 14:25:36 volumio3 volumio[1979]: [Metrics] WebUI: 11s 100.59ms Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreStateMachine::resetVolumioState Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreStateMachine::getcurrentVolume Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:36 volumio3 volumio[1979]: info: Volumio Network Manager: Network status updated: 0 Nov 26 14:25:36 volumio3 volumio[1979]: info: Cannot mount NAS NEC3 at system boot, trial number 1 ,retrying in 5 seconds Nov 26 14:25:36 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732598735 101 Nov 26 14:25:36 volumio3 volumio[1979]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 26 14:25:36 volumio3 volumio[1979]: info: Reloading queue from file Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreStateMachine::setRepeat true single undefined Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreStateMachine::pushState Nov 26 14:25:36 volumio3 volumio[1979]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreStateMachine::setRandom false Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreStateMachine::pushState Nov 26 14:25:36 volumio3 volumio[1979]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:36 volumio3 volumio[1979]: info: Setting Device type: Raspberry PI Nov 26 14:25:36 volumio3 volumio[1979]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreStateMachine::pushState Nov 26 14:25:36 volumio3 volumio[1979]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreStateMachine::updateTrackBlock Nov 26 14:25:36 volumio3 volumio[1979]: info: CorePlayQueue::getTrackBlock Nov 26 14:25:36 volumio3 volumio[1979]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:36 volumio3 volumio[1979]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 26 14:25:36 volumio3 volumio[1979]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Nov 26 14:25:36 volumio3 volumio[1979]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 26 14:25:36 volumio3 volumio[1979]: info: Completed loading Core Plugins Nov 26 14:25:36 volumio3 volumio[1979]: info: Preparing to generate the ALSA configuration file Nov 26 14:25:37 volumio3 volumio[1979]: Starting albumart workers Nov 26 14:25:37 volumio3 volumio[1979]: info: Asound.conf file unchanged, so no further update is needed Nov 26 14:25:37 volumio3 volumio[1979]: info: Output device has changed, restarting MPD Nov 26 14:25:37 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:37 volumio3 volumio[1979]: info: Output device has changed, restarting Shairport Sync Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:37 volumio3 sudo[2113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:25:37 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:37 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:37 volumio3 sudo[2113]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:37 volumio3 sudo[2113]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:37 volumio3 sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:25:37 volumio3 sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:37 volumio3 volumio[1979]: Starting albumart workers Nov 26 14:25:37 volumio3 systemd[1]: Stopping Music Player Daemon... Nov 26 14:25:37 volumio3 volumio[1979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:37 volumio3 volumio[1979]: info: ___________ START PLUGINS ___________ Nov 26 14:25:37 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:25:37 volumio3 volumio[1979]: info: ControllerMpd::onStart: Initializing MPD Nov 26 14:25:37 volumio3 volumio[1979]: info: Creating MPD Configuration file Nov 26 14:25:37 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:25:37 volumio3 volumio[1979]: Starting albumart workers Nov 26 14:25:37 volumio3 sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:37 volumio3 volumio[1979]: info: [1732598737315] CoreMusicLibrary::Adding element DLNAサーバー Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:37 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:25:37 volumio3 sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:37 volumio3 sudo[2126]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:37 volumio3 sudo[2128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:37 volumio3 sudo[2128]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:37 volumio3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 26 14:25:37 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:25:37 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:25:37 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:25:37 volumio3 volumio[1979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:37 volumio3 volumio[1979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:37 volumio3 volumio[1979]: info: [1732598737476] CoreMusicLibrary::Adding element Last_100 Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:37 volumio3 volumio[1979]: info: [1732598737480] CoreMusicLibrary::Adding element Webradio Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:37 volumio3 volumio[1979]: info: Initializing BBC Radios Nov 26 14:25:37 volumio3 sudo[2134]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 14:25:37 volumio3 sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:37 volumio3 sudo[2134]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:37 volumio3 volumio[1979]: info: [squeezelite_mc] Starting proxy server... Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:37 volumio3 volumio[1979]: info: [1732598737656] CoreMusicLibrary::Adding element YouTube2 Nov 26 14:25:37 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:37 volumio3 volumio[1979]: Cannot find translation for source YouTube2 Nov 26 14:25:37 volumio3 volumio[1979]: info: Loading i18n strings for locale ja Nov 26 14:25:37 volumio3 volumio[1979]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:25:37 volumio3 volumio[1979]: info: Volumio Calling Home Nov 26 14:25:37 volumio3 sudo[2151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 14:25:37 volumio3 sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:37 volumio3 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 14:25:37 volumio3 squeezeboxserver[1942]: [24-11-26 14:25:33.2812] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:25:38 volumio3 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 14:25:38 volumio3 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 14:25:38 volumio3 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 14:25:38 volumio3 volumio[1979]: info: [squeezelite_mc] Proxy server started on port 44349 Nov 26 14:25:38 volumio3 systemd[1]: Started Logitech Media Server Daemon. Nov 26 14:25:38 volumio3 sudo[2151]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:38 volumio3 volumio[1979]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 14:25:38 volumio3 volumio[1979]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 14:25:38 volumio3 volumio[1979]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 14:25:38 volumio3 volumio[1979]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 14:25:38 volumio3 volumio[1979]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 14:25:38 volumio3 volumio[1979]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 14:25:38 volumio3 volumio[1979]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 14:25:38 volumio3 volumio[1979]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 14:25:38 volumio3 volumio[1979]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 14:25:38 volumio3 volumio[1979]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 14:25:38 volumio3 volumio[1979]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 14:25:38 volumio3 volumio[1979]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 14:25:38 volumio3 volumio[1979]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 14:25:38 volumio3 volumio[1979]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 14:25:38 volumio3 volumio[1979]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 14:25:38 volumio3 volumio[1979]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 14:25:38 volumio3 volumio[1979]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 14:25:38 volumio3 volumio[1979]: info: minidlna: Found miniDLNA version 1.2.1 Nov 26 14:25:38 volumio3 volumio[1979]: info: minidlna: Starting minidlna.service Nov 26 14:25:38 volumio3 volumio[1979]: info: logitechmediaserver started Nov 26 14:25:38 volumio3 volumio[1979]: info: MPD Permissions set Nov 26 14:25:38 volumio3 volumio[1979]: info: MPD Permissions set Nov 26 14:25:38 volumio3 volumio[1979]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreStateMachine::pushState Nov 26 14:25:38 volumio3 volumio[1979]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:38 volumio3 sudo[2185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Nov 26 14:25:38 volumio3 sudo[2185]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:38 volumio3 volumio[1979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:38 volumio3 systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Nov 26 14:25:38 volumio3 sudo[2185]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:38 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreStateMachine::pushState Nov 26 14:25:39 volumio3 volumio[1979]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:39 volumio3 volumio[1979]: info: [squeezelite_mc] Server discovery started Nov 26 14:25:39 volumio3 volumio[1979]: info: [squeezelite_mc] Player finder started Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:39 volumio3 volumio[1979]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 14:25:39 volumio3 volumio[1979]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:25:39 volumio3 systemd[1]: minidlna.service: Succeeded. Nov 26 14:25:39 volumio3 volumio[1979]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 14:25:39 volumio3 volumio[1979]: at doSend (dgram.js:692:16) Nov 26 14:25:39 volumio3 volumio[1979]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 26 14:25:39 volumio3 volumio[1979]: at afterDns (dgram.js:638:5) Nov 26 14:25:39 volumio3 volumio[1979]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 26 14:25:39 volumio3 volumio[1979]: errno: -101, Nov 26 14:25:39 volumio3 volumio[1979]: code: 'ENETUNREACH', Nov 26 14:25:39 volumio3 volumio[1979]: syscall: 'send', Nov 26 14:25:39 volumio3 volumio[1979]: address: '255.255.255.255', Nov 26 14:25:39 volumio3 volumio[1979]: port: 3483 Nov 26 14:25:39 volumio3 volumio[1979]: } Nov 26 14:25:39 volumio3 volumio[1979]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:25:39 volumio3 mpd[2145]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 14:25:39 volumio3 mpd[2145]: output: No 'audio_output' defined in config file Nov 26 14:25:39 volumio3 mpd[2145]: output: Successfully detected a sndio audio device Nov 26 14:25:39 volumio3 mpd[2145]: zeroconf: No global port, disabling zeroconf Nov 26 14:25:39 volumio3 systemd[1]: Started Music Player Daemon. Nov 26 14:25:39 volumio3 sudo[2128]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:39 volumio3 sudo[2117]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:39 volumio3 sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 14:24 Nov 26 14:25:39 volumio3 sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:40 volumio3 sudo[2227]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:40 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 26 14:25:40 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 26 14:25:40 volumio3 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 26 14:25:40 volumio3 ntfs-3g[2055]: Unmounting /dev/sda1 (My Passport) Nov 26 14:25:40 volumio3 systemd[1]: media-My_Passport.mount: Succeeded. Nov 26 14:25:40 volumio3 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 26 14:25:40 volumio3 systemd[1]: Started dynamicswap service. Nov 26 14:25:40 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 26 14:25:40 volumio3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 26 14:25:40 volumio3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Nov 26 14:25:40 volumio3 systemd[1]: Stopped Volumio Backend Module. Nov 26 14:25:40 volumio3 systemd[1]: Started Volumio Backend Module. Nov 26 14:25:40 volumio3 systemd[1]: Started dynamicswap service. Nov 26 14:25:40 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 26 14:25:42 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:42 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:42 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:42 volumio3 volumio[2242]: info: ------------------------------------------- Nov 26 14:25:42 volumio3 volumio[2242]: info: ----- Volumio3 ---- Nov 26 14:25:42 volumio3 volumio[2242]: info: ------------------------------------------- Nov 26 14:25:42 volumio3 volumio[2242]: info: ----- System startup ---- Nov 26 14:25:42 volumio3 volumio[2242]: info: ------------------------------------------- Nov 26 14:25:43 volumio3 volumio[2242]: info: MYVOLUMIO Environment detected Nov 26 14:25:43 volumio3 volumio[2242]: info: Plugin folders cleanup Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning into folder /volumio/app/plugins/ Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning category audio_interface Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning category miscellanea Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning category music_service Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning category plugins.json Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning category system_controller Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning category user_interface Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning into folder /data/plugins/ Nov 26 14:25:43 volumio3 volumio[2242]: info: Scanning category music_service Nov 26 14:25:43 volumio3 volumio[2242]: info: Plugin folders cleanup completed Nov 26 14:25:43 volumio3 volumio[2242]: info: ------------------------------------------- Nov 26 14:25:43 volumio3 volumio[2242]: info: ----- Core plugins startup ---- Nov 26 14:25:43 volumio3 volumio[2242]: info: ------------------------------------------- Nov 26 14:25:43 volumio3 volumio[2242]: info: Loading plugins from folder /volumio/app/plugins/ Nov 26 14:25:43 volumio3 volumio[2242]: info: Adding plugin upnp to MyMusic Plugins Nov 26 14:25:43 volumio3 volumio[2242]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 26 14:25:43 volumio3 volumio[2242]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 26 14:25:43 volumio3 volumio[2242]: info: Loading plugins from folder /data/plugins/ Nov 26 14:25:43 volumio3 volumio[2242]: info: Loading plugin "system"... Nov 26 14:25:43 volumio3 volumio[2242]: info: Loading plugin "appearance"... Nov 26 14:25:44 volumio3 volumio[2242]: info: Loading plugin "network"... Nov 26 14:25:44 volumio3 volumio[2242]: info: Refreshing Cached IP Addresses Nov 26 14:25:45 volumio3 sudo[2277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 14:25:45 volumio3 sudo[2277]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:45 volumio3 sudo[2277]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:45 volumio3 sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 14:25:45 volumio3 sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:45 volumio3 volumio[2242]: info: Loading plugin "services"... Nov 26 14:25:45 volumio3 sudo[2279]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:45 volumio3 volumio[2242]: info: Loading plugin "alsa_controller"... Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:42.1269] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:42.7657] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7614] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7625] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7634] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7700] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7726] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7746] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7779] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7869] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:44.7910] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 26 14:25:45 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:45.1151] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. Nov 26 14:25:45 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:45 volumio3 volumio[2242]: info: Loading plugin "wizard"... Nov 26 14:25:45 volumio3 volumio[2242]: info: Loading plugin "networkfs"... Nov 26 14:25:45 volumio3 volumio[2242]: info: Starting Udev Watcher for removable devices Nov 26 14:25:45 volumio3 sudo[2303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kenshi,password=dke,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.20/share\\音楽 /mnt/NAS/NEC3 Nov 26 14:25:45 volumio3 sudo[2303]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:45 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:45] [connect] Successful connection Nov 26 14:25:45 volumio3 volumio[2242]: info: Ignoring mount for partition: boot Nov 26 14:25:45 volumio3 volumio[2242]: info: Ignoring mount for partition: volumio Nov 26 14:25:45 volumio3 kernel: CIFS: Attempting to mount //192.168.2.20/share/音楽 Nov 26 14:25:45 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 26 14:25:45 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Nov 26 14:25:45 volumio3 sudo[2303]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:45 volumio3 volumio[2242]: info: Ignoring mount for partition: volumio_data Nov 26 14:25:45 volumio3 volumio[2242]: info: Mounting Device My_Passport Nov 26 14:25:45 volumio3 sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/My_Passport -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:25:45 volumio3 sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:47 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:47 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:47 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:47 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:47.3338] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.4607] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (System error) Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5187] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5195] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.001 seconds. Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5223] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5291] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (System error) Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5303] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5312] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.001 seconds. Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5325] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5351] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5359] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.5395] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6578] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6603] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6626] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6724] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6883] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6895] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.157 seconds. Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6908] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6937] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.003 seconds. Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6969] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6976] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.002 seconds. Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.6992] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Nov 26 14:25:48 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:48.8265] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.126 seconds. Nov 26 14:25:48 volumio3 ntfs-3g[2319]: Version 2017.3.23AR.3 integrated FUSE 28 Nov 26 14:25:48 volumio3 ntfs-3g[2319]: Mounted /dev/sda1 (Read-Write, label "My Passport", NTFS 3.1) Nov 26 14:25:48 volumio3 ntfs-3g[2319]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:25:48 volumio3 ntfs-3g[2319]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Nov 26 14:25:48 volumio3 ntfs-3g[2319]: Global ownership and permissions enforced, configuration type 7 Nov 26 14:25:49 volumio3 sudo[2311]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:49 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "volumio_command_line_client"... Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "upnp"... Nov 26 14:25:49 volumio3 volumio[2242]: info: [1732598749029] Starting Upmpd Daemon Nov 26 14:25:49 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "my_music"... Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "mpd"... Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "upnp_browser"... Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "alarm-clock"... Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "airplay_emulation"... Nov 26 14:25:49 volumio3 volumio[2242]: info: Starting Shairport Sync Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "last_100"... Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "webradio"... Nov 26 14:25:49 volumio3 volumio[2242]: info: Loading plugin "i2s_dacs"... Nov 26 14:25:50 volumio3 volumio[2242]: info: I2S DAC not set, start Auto-detection Nov 26 14:25:50 volumio3 volumio[2242]: info: Loading plugin "volumiodiscovery"... Nov 26 14:25:50 volumio3 volumio[2242]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:50 volumio3 volumio[2242]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:50 volumio3 volumio[2242]: *** WARNING *** For more information see Nov 26 14:25:50 volumio3 volumio[2242]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:50 volumio3 volumio[2242]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:50 volumio3 volumio[2242]: *** WARNING *** For more information see Nov 26 14:25:50 volumio3 node[2242]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:50 volumio3 node[2242]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:50 volumio3 node[2242]: *** WARNING *** For more information see Nov 26 14:25:50 volumio3 node[2242]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:25:50 volumio3 node[2242]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:25:50 volumio3 node[2242]: *** WARNING *** For more information see Nov 26 14:25:50 volumio3 volumio[2242]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 14:25:50 volumio3 volumio[2242]: info: Discovery: Started advertising with name: Volumio3 Nov 26 14:25:50 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:25:50 volumio3 volumio[2242]: info: Loading plugin "squeezelite_mc"... Nov 26 14:25:50 volumio3 volumio[2242]: info: Loading plugin "youtube2"... Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading plugin "outputs"... Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading plugin "albumart"... Nov 26 14:25:52 volumio3 volumio[2242]: info: Plugin example_plugin is not enabled Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading plugin "inputs"... Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading plugin "updater_comm"... Nov 26 14:25:52 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:52 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:52 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:52 volumio3 volumio[2242]: info: Plugin mpdemulation is not enabled Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading plugin "rest_api"... Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading plugin "websocket"... Nov 26 14:25:52 volumio3 volumio[2242]: info: Starting Socket.io Server version 2.3.0 Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading plugin "lms"... Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading plugin "minidlna"... Nov 26 14:25:52 volumio3 volumio[2242]: Forking 3 albumart workers Nov 26 14:25:52 volumio3 volumio[2242]: info: Applying required configuration parameters for plugin minidlna Nov 26 14:25:52 volumio3 volumio[2242]: info: Loading i18n strings for locale ja Nov 26 14:25:52 volumio3 volumio[2242]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:25:52 volumio3 volumio[2242]: Updating browse sources language Nov 26 14:25:52 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::initPlayerControls Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: Express server listening on port 3000 Nov 26 14:25:53 volumio3 volumio[2242]: [Metrics] WebUI: 10s 983.53ms Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreStateMachine::resetVolumioState Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreStateMachine::getcurrentVolume Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: Volumio Network Manager: Network status updated: 0 Nov 26 14:25:53 volumio3 volumio[2242]: info: Cannot mount NAS NEC3 at system boot, trial number 1 ,retrying in 5 seconds Nov 26 14:25:53 volumio3 volumio[2242]: info: Reloading queue from file Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreStateMachine::setRepeat true single undefined Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreStateMachine::pushState Nov 26 14:25:53 volumio3 volumio[2242]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreStateMachine::setRandom false Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreStateMachine::pushState Nov 26 14:25:53 volumio3 volumio[2242]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:53 volumio3 volumio[2242]: info: Setting Device type: Raspberry PI Nov 26 14:25:53 volumio3 volumio[2242]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreStateMachine::pushState Nov 26 14:25:53 volumio3 volumio[2242]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreStateMachine::updateTrackBlock Nov 26 14:25:53 volumio3 volumio[2242]: info: CorePlayQueue::getTrackBlock Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:53 volumio3 volumio[2242]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 26 14:25:53 volumio3 volumio[2242]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Nov 26 14:25:53 volumio3 volumio[2242]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 26 14:25:53 volumio3 volumio[2242]: info: Completed loading Core Plugins Nov 26 14:25:53 volumio3 volumio[2242]: info: Preparing to generate the ALSA configuration file Nov 26 14:25:53 volumio3 volumio[2242]: Starting albumart workers Nov 26 14:25:53 volumio3 volumio[2242]: Starting albumart workers Nov 26 14:25:53 volumio3 volumio[2242]: Starting albumart workers Nov 26 14:25:53 volumio3 volumio[2242]: info: Asound.conf file unchanged, so no further update is needed Nov 26 14:25:53 volumio3 volumio[2242]: info: Output device has changed, restarting MPD Nov 26 14:25:53 volumio3 volumio[2242]: info: Output device has changed, restarting Shairport Sync Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:53 volumio3 sudo[2372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:25:53 volumio3 sudo[2372]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:53 volumio3 sudo[2372]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:53 volumio3 sudo[2373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:25:53 volumio3 sudo[2373]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:53 volumio3 volumio[2242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:53 volumio3 volumio[2242]: info: ___________ START PLUGINS ___________ Nov 26 14:25:53 volumio3 volumio[2242]: info: ControllerMpd::onStart: Initializing MPD Nov 26 14:25:53 volumio3 volumio[2242]: info: Creating MPD Configuration file Nov 26 14:25:53 volumio3 systemd[1]: Stopping Music Player Daemon... Nov 26 14:25:53 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:25:53 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:53 volumio3 volumio[2242]: info: [1732598753905] CoreMusicLibrary::Adding element DLNAサーバー Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:53 volumio3 sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:25:53 volumio3 sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:53 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:53 volumio3 sudo[2380]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:53 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:25:53 volumio3 sudo[2382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:25:53 volumio3 sudo[2382]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:53 volumio3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 26 14:25:53 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:25:53 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:25:54 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:25:54 volumio3 volumio[2242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:54 volumio3 volumio[2242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:54 volumio3 volumio[2242]: info: [1732598754088] CoreMusicLibrary::Adding element Last_100 Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:54 volumio3 volumio[2242]: info: [1732598754093] CoreMusicLibrary::Adding element Webradio Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:54 volumio3 volumio[2242]: info: Initializing BBC Radios Nov 26 14:25:54 volumio3 sudo[2389]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 14:25:54 volumio3 sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:54 volumio3 sudo[2389]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:54 volumio3 volumio[2242]: info: [squeezelite_mc] Starting proxy server... Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:25:54 volumio3 volumio[2242]: info: [1732598754257] CoreMusicLibrary::Adding element YouTube2 Nov 26 14:25:54 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:25:54 volumio3 volumio[2242]: Cannot find translation for source YouTube2 Nov 26 14:25:54 volumio3 volumio[2242]: info: Loading i18n strings for locale ja Nov 26 14:25:54 volumio3 volumio[2242]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:25:54 volumio3 sudo[2406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 14:25:54 volumio3 volumio[2242]: info: Volumio Calling Home Nov 26 14:25:54 volumio3 sudo[2406]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:54 volumio3 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 14:25:54 volumio3 squeezeboxserver[2160]: [24-11-26 14:25:49.0124] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:25:54 volumio3 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 14:25:54 volumio3 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 14:25:54 volumio3 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 14:25:54 volumio3 systemd[1]: Started Logitech Media Server Daemon. Nov 26 14:25:54 volumio3 sudo[2406]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:54 volumio3 volumio[2242]: info: [squeezelite_mc] Proxy server started on port 45525 Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:25:55 volumio3 volumio[2242]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 14:25:55 volumio3 volumio[2242]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 14:25:55 volumio3 volumio[2242]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 14:25:55 volumio3 volumio[2242]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 14:25:55 volumio3 volumio[2242]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 14:25:55 volumio3 volumio[2242]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 14:25:55 volumio3 volumio[2242]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 14:25:55 volumio3 volumio[2242]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 14:25:55 volumio3 volumio[2242]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 14:25:55 volumio3 volumio[2242]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 14:25:55 volumio3 volumio[2242]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 14:25:55 volumio3 volumio[2242]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 14:25:55 volumio3 volumio[2242]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 14:25:55 volumio3 volumio[2242]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 14:25:55 volumio3 volumio[2242]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 14:25:55 volumio3 volumio[2242]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 14:25:55 volumio3 volumio[2242]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 14:25:55 volumio3 volumio[2242]: info: minidlna: Found miniDLNA version 1.2.1 Nov 26 14:25:55 volumio3 volumio[2242]: info: minidlna: Starting minidlna.service Nov 26 14:25:55 volumio3 volumio[2242]: info: logitechmediaserver started Nov 26 14:25:55 volumio3 volumio[2242]: info: MPD Permissions set Nov 26 14:25:55 volumio3 volumio[2242]: info: MPD Permissions set Nov 26 14:25:55 volumio3 volumio[2242]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreStateMachine::pushState Nov 26 14:25:55 volumio3 volumio[2242]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:55 volumio3 sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Nov 26 14:25:55 volumio3 sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:55 volumio3 systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Nov 26 14:25:55 volumio3 sudo[2454]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:55 volumio3 volumio[2242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreStateMachine::pushState Nov 26 14:25:55 volumio3 volumio[2242]: info: CorePlayQueue::getTrack 0 Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::volumioPushState Nov 26 14:25:55 volumio3 volumio[2242]: info: [squeezelite_mc] Server discovery started Nov 26 14:25:55 volumio3 volumio[2242]: info: [squeezelite_mc] Player finder started Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:25:55 volumio3 volumio[2242]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 14:25:55 volumio3 volumio[2242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:25:55 volumio3 volumio[2242]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 14:25:55 volumio3 volumio[2242]: at doSend (dgram.js:692:16) Nov 26 14:25:55 volumio3 volumio[2242]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 26 14:25:55 volumio3 volumio[2242]: at afterDns (dgram.js:638:5) Nov 26 14:25:55 volumio3 volumio[2242]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 26 14:25:55 volumio3 volumio[2242]: errno: -101, Nov 26 14:25:55 volumio3 volumio[2242]: code: 'ENETUNREACH', Nov 26 14:25:55 volumio3 volumio[2242]: syscall: 'send', Nov 26 14:25:55 volumio3 volumio[2242]: address: '255.255.255.255', Nov 26 14:25:55 volumio3 volumio[2242]: port: 3483 Nov 26 14:25:55 volumio3 volumio[2242]: } Nov 26 14:25:55 volumio3 volumio[2242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:25:56 volumio3 mpd[2400]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 14:25:56 volumio3 mpd[2400]: output: No 'audio_output' defined in config file Nov 26 14:25:56 volumio3 mpd[2400]: output: Successfully detected a sndio audio device Nov 26 14:25:56 volumio3 mpd[2400]: zeroconf: No global port, disabling zeroconf Nov 26 14:25:56 volumio3 systemd[1]: Started Music Player Daemon. Nov 26 14:25:56 volumio3 sudo[2373]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:56 volumio3 sudo[2382]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:56 volumio3 sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 14:24 Nov 26 14:25:56 volumio3 sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:25:56 volumio3 sudo[2486]: pam_unix(sudo:session): session closed for user root Nov 26 14:25:56 volumio3 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 26 14:25:56 volumio3 ntfs-3g[2319]: Unmounting /dev/sda1 (My Passport) Nov 26 14:25:56 volumio3 systemd[1]: media-My_Passport.mount: Succeeded. Nov 26 14:25:56 volumio3 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 26 14:25:57 volumio3 systemd[1]: Started dynamicswap service. Nov 26 14:25:57 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 26 14:25:57 volumio3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 26 14:25:57 volumio3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Nov 26 14:25:57 volumio3 systemd[1]: Started dynamicswap service. Nov 26 14:25:57 volumio3 systemd[1]: Stopped Volumio Backend Module. Nov 26 14:25:57 volumio3 systemd[1]: Started Volumio Backend Module. Nov 26 14:25:57 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 26 14:25:57 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:25:57 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:25:57 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:25:57 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:57] [info] asio async_connect error: system:111 (Connection refused) Nov 26 14:25:57 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:57] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 26 14:25:57 volumio3 volumio-remote-updater[616]: [2024-11-26 14:25:57] [error] handle_connect error: Underlying Transport Error Nov 26 14:25:59 volumio3 volumio[2501]: info: ------------------------------------------- Nov 26 14:25:59 volumio3 volumio[2501]: info: ----- Volumio3 ---- Nov 26 14:25:59 volumio3 volumio[2501]: info: ------------------------------------------- Nov 26 14:25:59 volumio3 volumio[2501]: info: ----- System startup ---- Nov 26 14:25:59 volumio3 volumio[2501]: info: ------------------------------------------- Nov 26 14:26:00 volumio3 volumio[2501]: info: MYVOLUMIO Environment detected Nov 26 14:26:00 volumio3 volumio[2501]: info: Plugin folders cleanup Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning into folder /volumio/app/plugins/ Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning category audio_interface Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning category miscellanea Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning category music_service Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning category plugins.json Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning category system_controller Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning category user_interface Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning into folder /data/plugins/ Nov 26 14:26:00 volumio3 volumio[2501]: info: Scanning category music_service Nov 26 14:26:00 volumio3 volumio[2501]: info: Plugin folders cleanup completed Nov 26 14:26:00 volumio3 volumio[2501]: info: ------------------------------------------- Nov 26 14:26:00 volumio3 volumio[2501]: info: ----- Core plugins startup ---- Nov 26 14:26:00 volumio3 volumio[2501]: info: ------------------------------------------- Nov 26 14:26:00 volumio3 volumio[2501]: info: Loading plugins from folder /volumio/app/plugins/ Nov 26 14:26:00 volumio3 volumio[2501]: info: Adding plugin upnp to MyMusic Plugins Nov 26 14:26:00 volumio3 volumio[2501]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 26 14:26:00 volumio3 volumio[2501]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 26 14:26:00 volumio3 volumio[2501]: info: Loading plugins from folder /data/plugins/ Nov 26 14:26:00 volumio3 volumio[2501]: info: Loading plugin "system"... Nov 26 14:26:00 volumio3 volumio[2501]: info: Loading plugin "appearance"... Nov 26 14:26:01 volumio3 volumio[2501]: info: Loading plugin "network"... Nov 26 14:26:01 volumio3 volumio[2501]: info: Refreshing Cached IP Addresses Nov 26 14:26:01 volumio3 sudo[2585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 14:26:01 volumio3 sudo[2585]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:01 volumio3 sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 14:26:01 volumio3 sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:01 volumio3 volumio[2501]: info: Loading plugin "services"... Nov 26 14:26:01 volumio3 sudo[2585]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:01 volumio3 volumio[2501]: info: Loading plugin "alsa_controller"... Nov 26 14:26:01 volumio3 sudo[2587]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:01 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:26:01 volumio3 volumio[2501]: info: Loading plugin "wizard"... Nov 26 14:26:01 volumio3 volumio[2501]: info: Loading plugin "networkfs"... Nov 26 14:26:01 volumio3 volumio[2501]: info: Starting Udev Watcher for removable devices Nov 26 14:26:01 volumio3 sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kenshi,password=dke,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.20/share\\音楽 /mnt/NAS/NEC3 Nov 26 14:26:01 volumio3 sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:01 volumio3 sudo[2610]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:01 volumio3 kernel: CIFS: Attempting to mount //192.168.2.20/share/音楽 Nov 26 14:26:01 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 26 14:26:01 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Nov 26 14:26:01 volumio3 volumio[2501]: info: Ignoring mount for partition: boot Nov 26 14:26:01 volumio3 volumio[2501]: info: Ignoring mount for partition: volumio Nov 26 14:26:01 volumio3 volumio[2501]: info: Ignoring mount for partition: volumio_data Nov 26 14:26:01 volumio3 volumio[2501]: info: Mounting Device My_Passport Nov 26 14:26:01 volumio3 sudo[2618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/My_Passport -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:26:01 volumio3 sudo[2618]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:25:58.7932] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:25:59.4346] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.4924] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.4933] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.4940] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.5003] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.5030] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.5051] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.5085] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.5186] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.5240] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 26 14:26:01 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:01.8485] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. Nov 26 14:26:02 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:26:02 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:26:02 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:26:04 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:04.0326] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.1454] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (System error) Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2023] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2032] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.001 seconds. Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2059] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2126] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (System error) Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2139] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2148] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.001 seconds. Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2159] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2184] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2192] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.2228] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3400] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3426] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3447] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3546] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3704] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3716] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.156 seconds. Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3732] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3758] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.003 seconds. Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3790] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3797] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.002 seconds. Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.3812] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Nov 26 14:26:05 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.5083] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.126 seconds. Nov 26 14:26:05 volumio3 ntfs-3g[2627]: Version 2017.3.23AR.3 integrated FUSE 28 Nov 26 14:26:05 volumio3 ntfs-3g[2627]: Mounted /dev/sda1 (Read-Write, label "My Passport", NTFS 3.1) Nov 26 14:26:05 volumio3 ntfs-3g[2627]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 26 14:26:05 volumio3 ntfs-3g[2627]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Nov 26 14:26:05 volumio3 ntfs-3g[2627]: Global ownership and permissions enforced, configuration type 7 Nov 26 14:26:05 volumio3 sudo[2618]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:05 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:26:05 volumio3 volumio[2501]: info: Loading plugin "volumio_command_line_client"... Nov 26 14:26:05 volumio3 volumio[2501]: info: Loading plugin "upnp"... Nov 26 14:26:05 volumio3 volumio[2501]: info: [1732598765838] Starting Upmpd Daemon Nov 26 14:26:05 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:26:05 volumio3 volumio[2501]: info: Loading plugin "my_music"... Nov 26 14:26:05 volumio3 volumio[2501]: info: Loading plugin "mpd"... Nov 26 14:26:06 volumio3 volumio[2501]: info: Loading plugin "upnp_browser"... Nov 26 14:26:06 volumio3 volumio[2501]: info: Loading plugin "alarm-clock"... Nov 26 14:26:06 volumio3 volumio[2501]: info: Loading plugin "airplay_emulation"... Nov 26 14:26:06 volumio3 volumio[2501]: info: Starting Shairport Sync Nov 26 14:26:06 volumio3 volumio[2501]: info: Loading plugin "last_100"... Nov 26 14:26:06 volumio3 volumio[2501]: info: Loading plugin "webradio"... Nov 26 14:26:06 volumio3 volumio[2501]: info: Loading plugin "i2s_dacs"... Nov 26 14:26:06 volumio3 volumio[2501]: info: I2S DAC not set, start Auto-detection Nov 26 14:26:06 volumio3 volumio[2501]: info: Loading plugin "volumiodiscovery"... Nov 26 14:26:06 volumio3 volumio[2501]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:26:06 volumio3 volumio[2501]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:26:06 volumio3 volumio[2501]: *** WARNING *** For more information see Nov 26 14:26:06 volumio3 volumio[2501]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:26:06 volumio3 volumio[2501]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:26:06 volumio3 volumio[2501]: *** WARNING *** For more information see Nov 26 14:26:06 volumio3 node[2501]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 14:26:06 volumio3 node[2501]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:26:06 volumio3 node[2501]: *** WARNING *** For more information see Nov 26 14:26:06 volumio3 node[2501]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 14:26:06 volumio3 node[2501]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 14:26:06 volumio3 node[2501]: *** WARNING *** For more information see Nov 26 14:26:06 volumio3 volumio[2501]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 14:26:06 volumio3 volumio[2501]: info: Discovery: Started advertising with name: Volumio3 Nov 26 14:26:06 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 14:26:06 volumio3 volumio[2501]: info: Loading plugin "squeezelite_mc"... Nov 26 14:26:07 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:26:07 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:26:07 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:26:07 volumio3 volumio[2501]: info: Loading plugin "youtube2"... Nov 26 14:26:07 volumio3 volumio-remote-updater[616]: [2024-11-26 14:26:07] [connect] Successful connection Nov 26 14:26:08 volumio3 dhcpcd[713]: eth0: carrier acquired Nov 26 14:26:08 volumio3 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 26 14:26:08 volumio3 dhcpcd[713]: DUID 00:01:00:01:2c:2b:35:41:b8:27:eb:4d:20:37 Nov 26 14:26:08 volumio3 dhcpcd[713]: eth0: IAID eb:4d:20:37 Nov 26 14:26:08 volumio3 dhcpcd[713]: eth0: adding address fe80::ba27:ebff:fe4d:2037 Nov 26 14:26:08 volumio3 dhcpcd[713]: ipv6_addaddr1: Permission denied Nov 26 14:26:08 volumio3 dhcpcd[713]: eth0: probing address 192.168.2.35/24 Nov 26 14:26:08 volumio3 dhcpcd[713]: eth0: soliciting an IPv6 router Nov 26 14:26:08 volumio3 ifplugd(eth0)[863]: Link beat detected. Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading plugin "outputs"... Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading plugin "albumart"... Nov 26 14:26:09 volumio3 volumio[2501]: info: Plugin example_plugin is not enabled Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading plugin "inputs"... Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading plugin "updater_comm"... Nov 26 14:26:09 volumio3 ifplugd(eth0)[863]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 26 14:26:09 volumio3 ifplugd(eth0)[863]: client: ifup: interface eth0 already configured Nov 26 14:26:09 volumio3 ifplugd(eth0)[863]: Program executed successfully. Nov 26 14:26:09 volumio3 volumio[2501]: info: Plugin mpdemulation is not enabled Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading plugin "rest_api"... Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading plugin "websocket"... Nov 26 14:26:09 volumio3 volumio[2501]: info: Starting Socket.io Server version 2.3.0 Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading plugin "lms"... Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading plugin "minidlna"... Nov 26 14:26:09 volumio3 volumio[2501]: Forking 3 albumart workers Nov 26 14:26:09 volumio3 volumio[2501]: info: Applying required configuration parameters for plugin minidlna Nov 26 14:26:09 volumio3 volumio[2501]: info: Loading i18n strings for locale ja Nov 26 14:26:09 volumio3 volumio[2501]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:26:09 volumio3 volumio[2501]: Updating browse sources language Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::initPlayerControls Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:26:09 volumio3 volumio[2501]: Express server listening on port 3000 Nov 26 14:26:09 volumio3 volumio[2501]: [Metrics] WebUI: 11s 251.25ms Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreStateMachine::resetVolumioState Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreStateMachine::getcurrentVolume Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:26:09 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:26:10 volumio3 volumio[2501]: info: Volumio Network Manager: Network status updated: 0 Nov 26 14:26:10 volumio3 volumio[2501]: info: Cannot mount NAS NEC3 at system boot, trial number 1 ,retrying in 5 seconds Nov 26 14:26:10 volumio3 volumio-remote-updater[616]: [2024-11-26 14:26:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732598767 101 Nov 26 14:26:10 volumio3 volumio[2501]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 26 14:26:10 volumio3 volumio[2501]: info: Reloading queue from file Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreStateMachine::setRepeat true single undefined Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreStateMachine::pushState Nov 26 14:26:10 volumio3 volumio[2501]: info: CorePlayQueue::getTrack 0 Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::volumioPushState Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreStateMachine::setRandom false Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreStateMachine::pushState Nov 26 14:26:10 volumio3 volumio[2501]: info: CorePlayQueue::getTrack 0 Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::volumioPushState Nov 26 14:26:10 volumio3 volumio[2501]: info: Setting Device type: Raspberry PI Nov 26 14:26:10 volumio3 volumio[2501]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 26 14:26:10 volumio3 volumio[2501]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Nov 26 14:26:10 volumio3 volumio[2501]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 26 14:26:10 volumio3 volumio[2501]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreStateMachine::pushState Nov 26 14:26:10 volumio3 volumio[2501]: info: CorePlayQueue::getTrack 0 Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::volumioPushState Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreStateMachine::updateTrackBlock Nov 26 14:26:10 volumio3 volumio[2501]: info: CorePlayQueue::getTrackBlock Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:26:10 volumio3 volumio[2501]: info: Completed loading Core Plugins Nov 26 14:26:10 volumio3 volumio[2501]: info: Preparing to generate the ALSA configuration file Nov 26 14:26:10 volumio3 volumio[2501]: info: Asound.conf file unchanged, so no further update is needed Nov 26 14:26:10 volumio3 volumio[2501]: info: Output device has changed, restarting MPD Nov 26 14:26:10 volumio3 volumio[2501]: Starting albumart workers Nov 26 14:26:10 volumio3 volumio[2501]: Starting albumart workers Nov 26 14:26:10 volumio3 volumio[2501]: Starting albumart workers Nov 26 14:26:10 volumio3 volumio[2501]: info: Output device has changed, restarting Shairport Sync Nov 26 14:26:10 volumio3 sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:26:10 volumio3 sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:10 volumio3 sudo[2684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:26:10 volumio3 sudo[2684]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:10 volumio3 sudo[2683]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:10 volumio3 systemd[1]: Stopping Music Player Daemon... Nov 26 14:26:10 volumio3 volumio[2501]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:26:10 volumio3 volumio[2501]: info: ___________ START PLUGINS ___________ Nov 26 14:26:10 volumio3 volumio[2501]: info: ControllerMpd::onStart: Initializing MPD Nov 26 14:26:10 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:26:10 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:26:10 volumio3 volumio[2501]: info: Creating MPD Configuration file Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:26:10 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:26:10 volumio3 volumio[2501]: info: [1732598770728] CoreMusicLibrary::Adding element DLNAサーバー Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:26:10 volumio3 sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 14:26:10 volumio3 sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:26:10 volumio3 sudo[2694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 14:26:10 volumio3 sudo[2692]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:10 volumio3 sudo[2694]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:10 volumio3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 26 14:26:10 volumio3 systemd[1]: mpd.service: Succeeded. Nov 26 14:26:10 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 26 14:26:10 volumio3 volumio[2501]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:26:10 volumio3 systemd[1]: Starting Music Player Daemon... Nov 26 14:26:10 volumio3 volumio[2501]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:26:10 volumio3 volumio[2501]: info: [1732598770994] CoreMusicLibrary::Adding element Last_100 Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:26:10 volumio3 volumio[2501]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:26:11 volumio3 volumio[2501]: info: [1732598770999] CoreMusicLibrary::Adding element Webradio Nov 26 14:26:11 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:26:11 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:26:11 volumio3 volumio[2501]: info: Initializing BBC Radios Nov 26 14:26:11 volumio3 sudo[2700]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 14:26:11 volumio3 sudo[2700]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:11 volumio3 sudo[2700]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:11 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 14:26:11 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:26:11 volumio3 volumio[2501]: info: [squeezelite_mc] Starting proxy server... Nov 26 14:26:11 volumio3 volumio[2501]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 14:26:11 volumio3 volumio[2501]: info: [1732598771195] CoreMusicLibrary::Adding element YouTube2 Nov 26 14:26:11 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 14:26:11 volumio3 volumio[2501]: Cannot find translation for source YouTube2 Nov 26 14:26:11 volumio3 volumio[2501]: info: Loading i18n strings for locale ja Nov 26 14:26:11 volumio3 volumio[2501]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Nov 26 14:26:11 volumio3 sudo[2719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 14:26:11 volumio3 sudo[2719]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:11 volumio3 volumio[2501]: info: Volumio Calling Home Nov 26 14:26:11 volumio3 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 14:26:11 volumio3 squeezeboxserver[2415]: [24-11-26 14:26:05.6805] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 26 14:26:11 volumio3 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 14:26:11 volumio3 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 14:26:11 volumio3 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 14:26:11 volumio3 volumio[2501]: info: [squeezelite_mc] Proxy server started on port 43245 Nov 26 14:26:11 volumio3 systemd[1]: Started Logitech Media Server Daemon. Nov 26 14:26:11 volumio3 sudo[2719]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:11 volumio3 volumio[2501]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 14:26:11 volumio3 volumio[2501]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 14:26:11 volumio3 volumio[2501]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 14:26:11 volumio3 volumio[2501]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 14:26:11 volumio3 volumio[2501]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 14:26:11 volumio3 volumio[2501]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 14:26:11 volumio3 volumio[2501]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 14:26:11 volumio3 volumio[2501]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 14:26:11 volumio3 volumio[2501]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 14:26:11 volumio3 volumio[2501]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 14:26:11 volumio3 volumio[2501]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 14:26:11 volumio3 volumio[2501]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 14:26:11 volumio3 volumio[2501]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 14:26:11 volumio3 volumio[2501]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 14:26:11 volumio3 volumio[2501]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 14:26:11 volumio3 volumio[2501]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 14:26:11 volumio3 volumio[2501]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 14:26:11 volumio3 volumio[2501]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 14:26:12 volumio3 volumio[2501]: info: minidlna: Found miniDLNA version 1.2.1 Nov 26 14:26:12 volumio3 volumio[2501]: info: minidlna: Starting minidlna.service Nov 26 14:26:12 volumio3 volumio[2501]: info: logitechmediaserver started Nov 26 14:26:12 volumio3 volumio[2501]: info: MPD Permissions set Nov 26 14:26:12 volumio3 volumio[2501]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreStateMachine::pushState Nov 26 14:26:12 volumio3 volumio[2501]: info: CorePlayQueue::getTrack 0 Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::volumioPushState Nov 26 14:26:12 volumio3 sudo[2769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Nov 26 14:26:12 volumio3 sudo[2769]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 14:26:12 volumio3 sudo[2769]: pam_unix(sudo:session): session closed for user root Nov 26 14:26:12 volumio3 volumio[2501]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 26 14:26:12 volumio3 volumio[2501]: info: minidlna: systemctl start minidlna.service succeeded. Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: VolumeController:: Volume=24 Mute =false Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreStateMachine::pushState Nov 26 14:26:12 volumio3 volumio[2501]: info: CorePlayQueue::getTrack 0 Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::volumioPushState Nov 26 14:26:12 volumio3 volumio[2501]: info: [squeezelite_mc] Server discovery started Nov 26 14:26:12 volumio3 volumio[2501]: info: [squeezelite_mc] Player finder started Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 14:26:12 volumio3 volumio[2501]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 14:26:12 volumio3 volumio[2501]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:26:12 volumio3 volumio[2501]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 14:26:12 volumio3 volumio[2501]: at doSend (dgram.js:692:16) Nov 26 14:26:12 volumio3 volumio[2501]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 26 14:26:12 volumio3 volumio[2501]: at afterDns (dgram.js:638:5) Nov 26 14:26:12 volumio3 volumio[2501]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 26 14:26:12 volumio3 volumio[2501]: errno: -101, Nov 26 14:26:12 volumio3 volumio[2501]: code: 'ENETUNREACH', Nov 26 14:26:12 volumio3 volumio[2501]: syscall: 'send', Nov 26 14:26:12 volumio3 volumio[2501]: address: '255.255.255.255', Nov 26 14:26:12 volumio3 volumio[2501]: port: 3483 Nov 26 14:26:12 volumio3 volumio[2501]: } Nov 26 14:26:12 volumio3 volumio[2501]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 14:26:12 volumio3 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Nov 26 14:26:12 volumio3 volumio-time-update[613]: volumio-time-update-util: Date not found in response Nov 26 14:26:12 volumio3 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 14:26:13 volumio3 sudo[2796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 14:25 Nov 26 14:26:13 volumio3 sudo[2796]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"