-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2024-05-06 17:38:35 UTC. -- May 06 17:38:18 volumio-living-room systemd[1]: Starting Daily apt download activities... May 06 17:38:18 volumio-living-room ntpd[673]: receive: Unexpected origin timestamp 0xe8e8f1b4.ab631286 does not match aorg 0000000000.00000000 from server@44.190.5.123 xmt 0xe9e3940a.cbb87a0e May 06 17:38:18 volumio-living-room ntpd[673]: receive: Unexpected origin timestamp 0xe8e8f1b4.ab59d07e does not match aorg 0000000000.00000000 from server@155.248.196.28 xmt 0xe9e3940a.cc14d7e3 May 06 17:38:18 volumio-living-room ntpd[673]: receive: Unexpected origin timestamp 0xe8e8f1b4.ab609a70 does not match aorg 0000000000.00000000 from server@65.100.46.166 xmt 0xe9e3940a.ccaa864e May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.401874, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 06 17:38:20 volumio-living-room nmbd[656]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.402513, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 06 17:38:20 volumio-living-room nmbd[656]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.402671, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 06 17:38:20 volumio-living-room nmbd[656]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.402829, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 06 17:38:20 volumio-living-room nmbd[656]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.402929, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 06 17:38:20 volumio-living-room nmbd[656]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.403117, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 06 17:38:20 volumio-living-room nmbd[656]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.403233, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 06 17:38:20 volumio-living-room nmbd[656]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.403338, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 06 17:38:20 volumio-living-room nmbd[656]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.403433, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 06 17:38:20 volumio-living-room nmbd[656]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.403535, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 06 17:38:20 volumio-living-room nmbd[656]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 06 17:38:20 volumio-living-room nmbd[656]: [2024/05/06 17:38:20.403635, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 06 17:38:20 volumio-living-room nmbd[656]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 06 17:38:21 volumio-living-room volumio[1348]: info: ------------------------------------------- May 06 17:38:21 volumio-living-room volumio[1348]: info: ----- Volumio3 ---- May 06 17:38:21 volumio-living-room volumio[1348]: info: ------------------------------------------- May 06 17:38:21 volumio-living-room volumio[1348]: info: ----- System startup ---- May 06 17:38:21 volumio-living-room volumio[1348]: info: ------------------------------------------- May 06 17:38:22 volumio-living-room volumio[1348]: info: MYVOLUMIO Environment detected May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin music_service/airplay_emulation. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin music_service/inputs. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin music_service/mpd. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin music_service/webradio. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin music_service/example_plugin. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin music_service/last_100. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin music_service/upnp_browser. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin audio_interface/outputs. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin audio_interface/upnp. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin audio_interface/alsa_controller. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin miscellanea/alarm-clock. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin miscellanea/albumart. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin miscellanea/appearance. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin miscellanea/wizard. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin miscellanea/my_music. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin user_interface/websocket. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin user_interface/mpdemulation. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin user_interface/rest_api. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin system_controller/updater_comm. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin system_controller/network. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin system_controller/networkfs. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin system_controller/services. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin system_controller/system. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin system_controller/i2s_dacs. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin system_controller/volumiodiscovery. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it May 06 17:38:22 volumio-living-room volumio[1348]: info: Plugin folders cleanup May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning into folder /volumio/app/plugins/ May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category audio_interface May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category miscellanea May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category music_service May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category plugins.json May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category system_controller May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category user_interface May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning into folder /data/plugins/ May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category audio_interface May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category music_service May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category system_hardware May 06 17:38:22 volumio-living-room volumio[1348]: info: Scanning category user_interface May 06 17:38:22 volumio-living-room volumio[1348]: info: Plugin folders cleanup completed May 06 17:38:22 volumio-living-room volumio[1348]: info: ------------------------------------------- May 06 17:38:22 volumio-living-room volumio[1348]: info: ----- Core plugins startup ---- May 06 17:38:22 volumio-living-room volumio[1348]: info: ------------------------------------------- May 06 17:38:22 volumio-living-room volumio[1348]: info: Loading plugins from folder /volumio/app/plugins/ May 06 17:38:22 volumio-living-room volumio[1348]: info: Adding plugin upnp to MyMusic Plugins May 06 17:38:22 volumio-living-room volumio[1348]: info: Adding plugin airplay_emulation to MyMusic Plugins May 06 17:38:22 volumio-living-room volumio[1348]: info: Adding plugin upnp_browser to MyMusic Plugins May 06 17:38:22 volumio-living-room volumio[1348]: info: Loading plugins from folder /data/plugins/ May 06 17:38:22 volumio-living-room volumio[1348]: info: Loading plugin "system"... May 06 17:38:22 volumio-living-room systemd[1]: apt-daily.service: Succeeded. May 06 17:38:22 volumio-living-room systemd[1]: Started Daily apt download activities. May 06 17:38:22 volumio-living-room systemd[1]: Starting Daily apt upgrade and clean activities... May 06 17:38:22 volumio-living-room volumio[1348]: info: No id defined. Creating one May 06 17:38:22 volumio-living-room volumio[1348]: info: Loading plugin "appearance"... May 06 17:38:23 volumio-living-room volumio-remote-updater[522]: [2024-05-06 17:38:23] [connect] Successful connection May 06 17:38:24 volumio-living-room volumio[1348]: info: Loading plugin "network"... May 06 17:38:24 volumio-living-room volumio[1348]: info: Refreshing Cached IP Addresses May 06 17:38:24 volumio-living-room volumio[1348]: info: Loading plugin "services"... May 06 17:38:24 volumio-living-room volumio[1348]: info: Loading plugin "alsa_controller"... May 06 17:38:24 volumio-living-room sudo[1458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 06 17:38:24 volumio-living-room sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 17:38:24 volumio-living-room sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 17:38:24 volumio-living-room sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:24 volumio-living-room sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:24 volumio-living-room sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:24 volumio-living-room sudo[1455]: pam_unix(sudo:session): session closed for user root May 06 17:38:24 volumio-living-room sudo[1452]: pam_unix(sudo:session): session closed for user root May 06 17:38:24 volumio-living-room volumio[1348]: warn: The ALSA output card is not set, defaulting to card [object Object] May 06 17:38:24 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 06 17:38:24 volumio-living-room volumio[1348]: info: Found match in Cards Database: setting mixer HDMI for card HDMI May 06 17:38:24 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 06 17:38:24 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 06 17:38:24 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 06 17:38:24 volumio-living-room systemd[1]: apt-daily-upgrade.service: Succeeded. May 06 17:38:24 volumio-living-room systemd[1]: Started Daily apt upgrade and clean activities. May 06 17:38:24 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 17:38:24 volumio-living-room volumio[1348]: info: Loading plugin "wizard"... May 06 17:38:24 volumio-living-room volumio[1348]: info: Loading plugin "volumio_command_line_client"... May 06 17:38:24 volumio-living-room volumio[1348]: info: Loading plugin "upnp"... May 06 17:38:24 volumio-living-room volumio[1348]: info: [1715017104789] Starting Upmpd Daemon May 06 17:38:24 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 17:38:24 volumio-living-room volumio[1348]: info: Loading plugin "my_music"... May 06 17:38:24 volumio-living-room volumio[1348]: info: Loading plugin "mpd"... May 06 17:38:25 volumio-living-room sudo[1458]: pam_unix(sudo:session): session closed for user root May 06 17:38:25 volumio-living-room volumio[1348]: info: Creating MPD Configuration file May 06 17:38:25 volumio-living-room volumio[1348]: info: Loading plugin "upnp_browser"... May 06 17:38:25 volumio-living-room sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 17:38:25 volumio-living-room sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:25 volumio-living-room sudo[1502]: pam_unix(sudo:session): session closed for user root May 06 17:38:25 volumio-living-room sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 17:38:25 volumio-living-room sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:25 volumio-living-room systemd[1]: musicservicesshield.service: Succeeded. May 06 17:38:25 volumio-living-room systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 06 17:38:25 volumio-living-room systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 06 17:38:25 volumio-living-room systemd[1]: Stopping Music Player Daemon... May 06 17:38:25 volumio-living-room systemd[1]: mpd.service: Succeeded. May 06 17:38:25 volumio-living-room systemd[1]: Stopped Music Player Daemon. May 06 17:38:25 volumio-living-room systemd[1]: Starting Music Player Daemon... May 06 17:38:25 volumio-living-room volumio[1348]: info: Loading plugin "networkfs"... May 06 17:38:26 volumio-living-room volumio[1348]: info: Cannot mount NAS Music01 at system boot, trial number 1 ,retrying in 5 seconds May 06 17:38:26 volumio-living-room volumio[1348]: info: Starting Udev Watcher for removable devices May 06 17:38:26 volumio-living-room volumio[1348]: info: Ignoring mount for partition: boot May 06 17:38:26 volumio-living-room volumio[1348]: info: Ignoring mount for partition: volumio May 06 17:38:26 volumio-living-room volumio[1348]: info: Ignoring mount for partition: volumio_data May 06 17:38:26 volumio-living-room volumio[1348]: error: Cannot associate FS Label, not mounting May 06 17:38:26 volumio-living-room volumio[1348]: info: Mounting Device 240GB May 06 17:38:26 volumio-living-room sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/240GB -o noatime May 06 17:38:26 volumio-living-room sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:27 volumio-living-room sudo[1515]: pam_unix(sudo:session): session closed for user root May 06 17:38:27 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 17:38:27 volumio-living-room volumio[1348]: info: Loading plugin "alarm-clock"... May 06 17:38:27 volumio-living-room volumio[1348]: info: Loading plugin "airplay_emulation"... May 06 17:38:27 volumio-living-room volumio[1348]: info: Starting Shairport Sync May 06 17:38:27 volumio-living-room volumio[1348]: info: Loading plugin "last_100"... May 06 17:38:27 volumio-living-room volumio[1348]: info: Loading plugin "webradio"... May 06 17:38:28 volumio-living-room mpd[1509]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 06 17:38:28 volumio-living-room mpd[1509]: config: Found database setting without music_directory - disabling database May 06 17:38:28 volumio-living-room mpd[1509]: output: No 'audio_output' defined in config file May 06 17:38:28 volumio-living-room mpd[1509]: output: Attempt to detect audio output device May 06 17:38:28 volumio-living-room mpd[1509]: output: Attempting to detect a alsa audio device May 06 17:38:28 volumio-living-room mpd[1509]: output: Successfully detected a alsa audio device May 06 17:38:28 volumio-living-room mpd[1509]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 06 17:38:28 volumio-living-room mpd[1509]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 06 17:38:28 volumio-living-room mpd[1509]: zeroconf: No global port, disabling zeroconf May 06 17:38:28 volumio-living-room systemd[1]: Started Music Player Daemon. May 06 17:38:28 volumio-living-room systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading plugin "i2s_dacs"... May 06 17:38:28 volumio-living-room sudo[1505]: pam_unix(sudo:session): session closed for user root May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading plugin "volumiodiscovery"... May 06 17:38:28 volumio-living-room volumio[1348]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 06 17:38:28 volumio-living-room volumio[1348]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 17:38:28 volumio-living-room volumio[1348]: *** WARNING *** For more information see May 06 17:38:28 volumio-living-room volumio[1348]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 06 17:38:28 volumio-living-room node[1348]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 06 17:38:28 volumio-living-room volumio[1348]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 17:38:28 volumio-living-room volumio[1348]: *** WARNING *** For more information see May 06 17:38:28 volumio-living-room node[1348]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 17:38:28 volumio-living-room node[1348]: *** WARNING *** For more information see May 06 17:38:28 volumio-living-room node[1348]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 06 17:38:28 volumio-living-room node[1348]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 17:38:28 volumio-living-room node[1348]: *** WARNING *** For more information see May 06 17:38:28 volumio-living-room volumio[1348]: info: Applying required configuration parameters for plugin volumiodiscovery May 06 17:38:28 volumio-living-room volumio[1348]: info: Discovery: Started advertising with name: undefined May 06 17:38:28 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading plugin "outputs"... May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading plugin "albumart"... May 06 17:38:28 volumio-living-room volumio[1348]: info: Plugin example_plugin is not enabled May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading plugin "inputs"... May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading plugin "updater_comm"... May 06 17:38:28 volumio-living-room volumio[1348]: info: Plugin mpdemulation is not enabled May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading plugin "rest_api"... May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading plugin "websocket"... May 06 17:38:28 volumio-living-room volumio[1348]: info: Plugin fusiondsp is not enabled May 06 17:38:28 volumio-living-room volumio[1348]: info: Plugin minidlna is not enabled May 06 17:38:28 volumio-living-room volumio[1348]: info: Plugin music_services_shield is not enabled May 06 17:38:28 volumio-living-room volumio[1348]: info: Plugin Systeminfo is not enabled May 06 17:38:28 volumio-living-room volumio[1348]: info: Plugin randomizer is not enabled May 06 17:38:28 volumio-living-room volumio[1348]: info: Loading i18n strings for locale en May 06 17:38:28 volumio-living-room volumio[1348]: Updating browse sources language May 06 17:38:28 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 17:38:28 volumio-living-room volumio[1348]: Forking 3 albumart workers May 06 17:38:29 volumio-living-room volumio[1522]: cset: --> shielding system active with May 06 17:38:29 volumio-living-room volumio[1522]: cset: "system" cpuset of CPUSPEC(1-3) with 93 tasks running May 06 17:38:29 volumio-living-room volumio[1522]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 06 17:38:29 volumio-living-room systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::initPlayerControls May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: Express server listening on port 3000 May 06 17:38:29 volumio-living-room volumio[1348]: [Metrics] WebUI: 8s 789.66ms May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreStateMachine::resetVolumioState May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreStateMachine::getcurrentVolume May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioRetrievevolume May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreStateMachine::getcurrentVolume May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioRetrievevolume May 06 17:38:29 volumio-living-room mpd[1509]: client: [0] opened from local May 06 17:38:29 volumio-living-room volumio[1348]: info: MPD Permissions set May 06 17:38:29 volumio-living-room volumio[1348]: info: MPD running with PID1509 May 06 17:38:29 volumio-living-room volumio[1348]: ,establishing connection May 06 17:38:29 volumio-living-room mpd[1509]: client: [1] opened from local May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 17:38:29 volumio-living-room volumio[1348]: info: Reloading queue from file May 06 17:38:29 volumio-living-room volumio[1348]: error: updateQueue error: null May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreStateMachine::setRepeat true single undefined May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreStateMachine::pushState May 06 17:38:29 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrack 0 May 06 17:38:29 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioPushState May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreStateMachine::setRandom false May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreStateMachine::pushState May 06 17:38:30 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrack 0 May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioPushState May 06 17:38:30 volumio-living-room volumio[1348]: info: Setting Device type: Raspberry PI May 06 17:38:30 volumio-living-room volumio[1348]: info: Completed loading Core Plugins May 06 17:38:30 volumio-living-room volumio[1348]: info: Preparing to generate the ALSA configuration file May 06 17:38:30 volumio-living-room volumio[1348]: info: VolumeController:: Volume=96 Mute =false May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreStateMachine::pushState May 06 17:38:30 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrack 0 May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioPushState May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreStateMachine::updateTrackBlock May 06 17:38:30 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrackBlock May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioRetrievevolume May 06 17:38:30 volumio-living-room volumio[1348]: info: VolumeController:: Volume=96 Mute =false May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreStateMachine::pushState May 06 17:38:30 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrack 0 May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioPushState May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreStateMachine::updateTrackBlock May 06 17:38:30 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrackBlock May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioRetrievevolume May 06 17:38:30 volumio-living-room volumio[1348]: error: updateQueue error: null May 06 17:38:30 volumio-living-room volumio[1348]: info: Asound.conf file written May 06 17:38:30 volumio-living-room volumio[1348]: Starting albumart workers May 06 17:38:30 volumio-living-room sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 06 17:38:30 volumio-living-room sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:30 volumio-living-room sudo[1589]: pam_unix(sudo:session): session closed for user root May 06 17:38:30 volumio-living-room volumio[1348]: Starting albumart workers May 06 17:38:30 volumio-living-room volumio[1348]: info: Output device has changed, restarting MPD May 06 17:38:30 volumio-living-room volumio[1348]: info: Output device has changed, restarting Shairport Sync May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 17:38:30 volumio-living-room sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 17:38:30 volumio-living-room sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:30 volumio-living-room sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 17:38:30 volumio-living-room sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:30 volumio-living-room sudo[1595]: pam_unix(sudo:session): session closed for user root May 06 17:38:30 volumio-living-room systemd[1]: musicservicesshield.service: Succeeded. May 06 17:38:30 volumio-living-room systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 06 17:38:30 volumio-living-room systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 06 17:38:30 volumio-living-room systemd[1]: Stopping Music Player Daemon... May 06 17:38:30 volumio-living-room volumio[1348]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 17:38:30 volumio-living-room volumio[1348]: info: ___________ START PLUGINS ___________ May 06 17:38:30 volumio-living-room systemd[1]: mpd.service: Succeeded. May 06 17:38:30 volumio-living-room systemd[1]: Stopped Music Player Daemon. May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 17:38:30 volumio-living-room volumio[1348]: info: [1715017110480] CoreMusicLibrary::Adding element Media Servers May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 17:38:30 volumio-living-room systemd[1]: Starting Music Player Daemon... May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 17:38:30 volumio-living-room volumio[1348]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 17:38:30 volumio-living-room volumio[1348]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 17:38:30 volumio-living-room volumio[1348]: info: [1715017110675] CoreMusicLibrary::Adding element Last_100 May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 17:38:30 volumio-living-room volumio[1348]: info: [1715017110681] CoreMusicLibrary::Adding element Webradio May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 17:38:30 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 17:38:30 volumio-living-room volumio[1348]: info: Volumio Calling Home May 06 17:38:30 volumio-living-room volumio[1348]: Starting albumart workers May 06 17:38:30 volumio-living-room volumio[1348]: info: Completed starting Core Plugins May 06 17:38:30 volumio-living-room volumio[1348]: info: ------------------------------------------- May 06 17:38:30 volumio-living-room volumio[1348]: info: ----- MyVolumio plugins startup ---- May 06 17:38:30 volumio-living-room volumio[1348]: info: ------------------------------------------- May 06 17:38:30 volumio-living-room volumio[1348]: info: [MyVolumio PluginManager] Fetching plans data.... May 06 17:38:31 volumio-living-room volumio[1348]: info: MPD Permissions set May 06 17:38:31 volumio-living-room volumio[1348]: info: Volumio called home May 06 17:38:31 volumio-living-room volumio[1348]: info: Cannot mount NAS Music01 at system boot, trial number 2 ,retrying in 5 seconds May 06 17:38:31 volumio-living-room volumio[1348]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 17:38:31 volumio-living-room volumio[1348]: info: VolumeController:: Volume=96 Mute =false May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreStateMachine::pushState May 06 17:38:31 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrack 0 May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioPushState May 06 17:38:31 volumio-living-room volumio[1348]: info: VolumeController:: Volume=96 Mute =false May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreStateMachine::pushState May 06 17:38:31 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrack 0 May 06 17:38:31 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioPushState May 06 17:38:31 volumio-living-room volumio[1348]: info: Starting Shairport Sync May 06 17:38:31 volumio-living-room volumio[1348]: info: Starting Shairport Sync May 06 17:38:31 volumio-living-room volumio[1348]: info: Starting Shairport Sync May 06 17:38:31 volumio-living-room sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 17:38:31 volumio-living-room sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:31 volumio-living-room sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 17:38:31 volumio-living-room sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:31 volumio-living-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 17:38:31 volumio-living-room systemd[1]: shairport-sync.service: Succeeded. May 06 17:38:31 volumio-living-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 17:38:32 volumio-living-room sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 17:38:32 volumio-living-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 17:38:32 volumio-living-room sudo[1626]: pam_unix(sudo:session): session closed for user root May 06 17:38:32 volumio-living-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 17:38:32 volumio-living-room sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 17:38:32 volumio-living-room systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 06 17:38:32 volumio-living-room systemd[1]: shairport-sync.service: Succeeded. May 06 17:38:32 volumio-living-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 17:38:32 volumio-living-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 17:38:32 volumio-living-room sudo[1628]: pam_unix(sudo:session): session closed for user root May 06 17:38:32 volumio-living-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 17:38:32 volumio-living-room systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 06 17:38:32 volumio-living-room systemd[1]: shairport-sync.service: Succeeded. May 06 17:38:32 volumio-living-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 17:38:32 volumio-living-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 17:38:32 volumio-living-room sudo[1642]: pam_unix(sudo:session): session closed for user root May 06 17:38:32 volumio-living-room volumio[1348]: info: Shairport-Sync Started May 06 17:38:32 volumio-living-room volumio[1348]: Error adding Membership: Error: addMembership EINVAL May 06 17:38:32 volumio-living-room volumio[1348]: info: Shairport-Sync Started May 06 17:38:32 volumio-living-room volumio[1348]: info: Shairport-Sync Started May 06 17:38:32 volumio-living-room volumio[1348]: info: CoreCommandRouter::volumioGetState May 06 17:38:32 volumio-living-room volumio[1348]: info: CorePlayQueue::getTrack 0 May 06 17:38:32 volumio-living-room mpd[1612]: May 06 17:38 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 06 17:38:33 volumio-living-room volumio[1348]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) May 06 17:38:33 volumio-living-room volumio[1348]: info: Discovery: Started advertising with name: undefined May 06 17:38:33 volumio-living-room mpd[1612]: May 06 17:38 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 06 17:38:33 volumio-living-room mpd[1612]: May 06 17:38 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 06 17:38:33 volumio-living-room systemd[1]: Started Music Player Daemon. May 06 17:38:33 volumio-living-room sudo[1597]: pam_unix(sudo:session): session closed for user root May 06 17:38:33 volumio-living-room systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 06 17:38:33 volumio-living-room volumio[1348]: error: updateQueue error: null May 06 17:38:34 volumio-living-room volumio[1662]: cset: --> shielding system active with May 06 17:38:34 volumio-living-room volumio[1662]: cset: "system" cpuset of CPUSPEC(1-3) with 105 tasks running May 06 17:38:34 volumio-living-room volumio[1662]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 06 17:38:34 volumio-living-room systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 06 17:38:34 volumio-living-room volumio[1348]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 17:38:34 volumio-living-room volumio[1348]: TypeError: Cannot read property 'charAt' of undefined May 06 17:38:34 volumio-living-room volumio[1348]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) May 06 17:38:34 volumio-living-room volumio[1348]: at listOnTimeout (internal/timers.js:554:17) May 06 17:38:34 volumio-living-room volumio[1348]: at processTimers (internal/timers.js:497:7) May 06 17:38:34 volumio-living-room volumio[1348]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 17:38:35 volumio-living-room sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-06 17:37 May 06 17:38:35 volumio-living-room sudo[1686]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"