-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Thu 2024-10-10 11:01:26 JST. -- Oct 10 11:00:19 motivo fake-hwclock[308]: Thu Oct 10 02:00:19 UTC 2024 Oct 10 11:00:19 motivo systemd[1]: Started Restore / save the current clock. Oct 10 11:00:19 motivo systemd[1]: Started Load/Save Random Seed. Oct 10 11:00:19 motivo systemd[1]: Mounted Kernel Configuration File System. Oct 10 11:00:19 motivo systemd[1]: Mounted FUSE Control File System. Oct 10 11:00:19 motivo systemd[1]: dynamicswap.service: Succeeded. Oct 10 11:00:19 motivo systemd[1]: Started Apply Kernel Variables. Oct 10 11:00:19 motivo systemd[1]: Started Create System Users. Oct 10 11:00:19 motivo systemd[1]: Starting Create Static Device Nodes in /dev... Oct 10 11:00:19 motivo systemd[1]: Started Create Static Device Nodes in /dev. Oct 10 11:00:19 motivo systemd[1]: Starting udev Kernel Device Manager... Oct 10 11:00:19 motivo systemd[1]: Reached target Local File Systems (Pre). Oct 10 11:00:19 motivo systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 10 11:00:19 motivo systemd[1]: Mounting /var/log... Oct 10 11:00:19 motivo systemd[1]: Mounting /var/spool/cups... Oct 10 11:00:19 motivo systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 10 11:00:19 motivo systemd[1]: Mounting /tmp... Oct 10 11:00:19 motivo systemd[1]: Mounted /var/log. Oct 10 11:00:19 motivo systemd[1]: Mounted /var/spool/cups. Oct 10 11:00:19 motivo systemd[1]: Mounting /var/spool/cups/tmp... Oct 10 11:00:19 motivo systemd[1]: Starting Flush Journal to Persistent Storage... Oct 10 11:00:19 motivo systemd[1]: Started udev Coldplug all Devices. Oct 10 11:00:19 motivo systemd[1]: Mounted /tmp. Oct 10 11:00:19 motivo systemd-udevd[340]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 10 11:00:19 motivo systemd[1]: Mounted /var/spool/cups/tmp. Oct 10 11:00:19 motivo systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 10 11:00:19 motivo systemd-journald[307]: Runtime journal (/run/log/journal/954740ce280c089d7ced5b1d669e79fc) is 7.5M, max 30.0M, 22.5M free. Oct 10 11:00:19 motivo systemd[1]: Started Flush Journal to Persistent Storage. Oct 10 11:00:19 motivo systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 10 11:00:19 motivo systemd[1]: Started udev Kernel Device Manager. Oct 10 11:00:19 motivo systemd[1]: Starting Show Plymouth Boot Screen... Oct 10 11:00:19 motivo systemd[1]: Received SIGRTMIN+20 from PID 366 (plymouthd). Oct 10 11:00:19 motivo systemd[1]: Started Show Plymouth Boot Screen. Oct 10 11:00:19 motivo systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 10 11:00:19 motivo systemd[1]: Reached target Local Encrypted Volumes. Oct 10 11:00:19 motivo systemd[1]: Reached target Paths. Oct 10 11:00:19 motivo systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 10 11:00:19 motivo systemd-udevd[363]: Using default interface naming scheme 'v240'. Oct 10 11:00:19 motivo kernel: mc: Linux media interface: v0.10 Oct 10 11:00:19 motivo systemd-udevd[363]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 10 11:00:19 motivo kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Oct 10 11:00:19 motivo kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Oct 10 11:00:19 motivo kernel: [vc_sm_connected_init]: start Oct 10 11:00:19 motivo kernel: [vc_sm_connected_init]: installed successfully Oct 10 11:00:19 motivo kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Oct 10 11:00:19 motivo kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Oct 10 11:00:19 motivo kernel: videodev: Linux video capture interface: v2.00 Oct 10 11:00:20 motivo kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 10 11:00:20 motivo kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Oct 10 11:00:20 motivo kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Oct 10 11:00:20 motivo kernel: cdc_acm 1-1.3:1.0: ttyACM0: USB ACM device Oct 10 11:00:20 motivo kernel: usbcore: registered new interface driver cdc_acm Oct 10 11:00:20 motivo kernel: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Oct 10 11:00:20 motivo kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 10 11:00:20 motivo kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Oct 10 11:00:20 motivo kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Oct 10 11:00:20 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Oct 10 11:00:20 motivo kernel: rpivid feb10000.codec: Device registered as /dev/video19 Oct 10 11:00:20 motivo kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Oct 10 11:00:20 motivo kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Oct 10 11:00:20 motivo kernel: brcmstb-i2c fef04500.i2c: @97500hz registered in polling mode Oct 10 11:00:20 motivo kernel: brcmstb-i2c fef09500.i2c: @97500hz registered in polling mode Oct 10 11:00:20 motivo systemd[1]: Found device /dev/disk/by-uuid/2179-32B9. Oct 10 11:00:20 motivo kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 10 11:00:20 motivo kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Oct 10 11:00:20 motivo kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Oct 10 11:00:20 motivo kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 10 11:00:20 motivo kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Oct 10 11:00:20 motivo systemd[1]: Starting File System Check on /dev/disk/by-uuid/2179-32B9... Oct 10 11:00:20 motivo systemd[1]: Started File System Check Daemon to report status. Oct 10 11:00:20 motivo systemd[1]: Found device /sys/subsystem/net/devices/eth0. Oct 10 11:00:20 motivo kernel: usb 1-1.4: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 10 11:00:20 motivo kernel: usb 1-1.4: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Oct 10 11:00:20 motivo kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Oct 10 11:00:20 motivo kernel: usb 1-1.4: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 10 11:00:20 motivo kernel: usb 1-1.4: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Oct 10 11:00:20 motivo kernel: usbcore: registered new interface driver snd-usb-audio Oct 10 11:00:20 motivo kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Oct 10 11:00:20 motivo kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-compute-module.bin failed with error -2 Oct 10 11:00:21 motivo kernel: usbcore: registered new interface driver brcmfmac Oct 10 11:00:21 motivo systemd-fsck[449]: fsck.fat 4.1 (2017-01-24) Oct 10 11:00:21 motivo systemd-fsck[449]: /dev/mmcblk0p1: 388 files, 94401/184582 clusters Oct 10 11:00:21 motivo kernel: [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0 Oct 10 11:00:21 motivo systemd[1]: Started File System Check on /dev/disk/by-uuid/2179-32B9. Oct 10 11:00:21 motivo kernel: Bluetooth: Core ver 2.22 Oct 10 11:00:21 motivo kernel: NET: Registered PF_BLUETOOTH protocol family Oct 10 11:00:21 motivo kernel: Bluetooth: HCI device and connection manager initialized Oct 10 11:00:21 motivo kernel: Bluetooth: HCI socket layer initialized Oct 10 11:00:21 motivo kernel: Bluetooth: L2CAP socket layer initialized Oct 10 11:00:21 motivo kernel: Bluetooth: SCO socket layer initialized Oct 10 11:00:21 motivo systemd-udevd[361]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 10 11:00:21 motivo kernel: Bluetooth: HCI UART driver ver 2.3 Oct 10 11:00:21 motivo kernel: Bluetooth: HCI UART protocol H4 registered Oct 10 11:00:21 motivo kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Oct 10 11:00:21 motivo kernel: Bluetooth: HCI UART protocol Broadcom registered Oct 10 11:00:21 motivo kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Oct 10 11:00:21 motivo kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Oct 10 11:00:21 motivo kernel: uart-pl011 fe201000.serial: no DMA platform data Oct 10 11:00:21 motivo systemd[1]: Mounting /boot... Oct 10 11:00:21 motivo kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Oct 10 11:00:21 motivo kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Oct 10 11:00:21 motivo kernel: Bluetooth: hci0: BCM: chip id 107 Oct 10 11:00:21 motivo kernel: Bluetooth: hci0: BCM: features 0x2f Oct 10 11:00:21 motivo kernel: Bluetooth: hci0: BCM4345C0 Oct 10 11:00:21 motivo kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Oct 10 11:00:21 motivo kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Oct 10 11:00:21 motivo systemd[1]: Mounted /boot. Oct 10 11:00:21 motivo systemd-udevd[357]: Using default interface naming scheme 'v240'. Oct 10 11:00:21 motivo systemd-udevd[357]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 10 11:00:21 motivo kernel: vc4_dsi fe700000.dsi: Fixed dependency cycle(s) with /soc/dsi@7e700000/panel@0 Oct 10 11:00:21 motivo kernel: mipi-dsi fe700000.dsi.0: Fixed dependency cycle(s) with /soc/dsi@7e700000 Oct 10 11:00:21 motivo kernel: panel-dsi-mt fe700000.dsi.0: supply power not found, using dummy regulator Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4]) Oct 10 11:00:21 motivo kernel: Registered IR keymap rc-cec Oct 10 11:00:21 motivo kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0 Oct 10 11:00:21 motivo kernel: input: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input0 Oct 10 11:00:21 motivo kernel: vc4_hdmi fef00700.hdmi: 'dmas' DT property is missing or empty, no HDMI audio Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fef00700.hdmi (ops vc4_hdmi_ops [vc4]) Oct 10 11:00:21 motivo kernel: Registered IR keymap rc-cec Oct 10 11:00:21 motivo kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1 Oct 10 11:00:21 motivo kernel: input: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1/input1 Oct 10 11:00:21 motivo kernel: vc4_hdmi fef05700.hdmi: 'dmas' DT property is missing or empty, no HDMI audio Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fef05700.hdmi (ops vc4_hdmi_ops [vc4]) Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fe700000.dsi (ops vc4_dsi_ops [vc4]) Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fe004000.txp (ops vc4_txp_ops [vc4]) Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fe206000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fe207000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 10 11:00:21 motivo kernel: Goodix-TS 6-005d: supply AVDD28 not found, using dummy regulator Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fe20a000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 10 11:00:21 motivo kernel: Goodix-TS 6-005d: supply VDDIO not found, using dummy regulator Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fe216000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 10 11:00:21 motivo kernel: vc4-drm gpu: bound fec12000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 10 11:00:21 motivo kernel: [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1 Oct 10 11:00:21 motivo kernel: wm8804 1-003b: revision E Oct 10 11:00:21 motivo kernel: Goodix-TS 6-005d: ID 911, version: 1060 Oct 10 11:00:21 motivo kernel: Goodix-TS 6-005d: Direct firmware load for goodix_911_cfg.bin failed with error -2 Oct 10 11:00:21 motivo kernel: input: 6-005d Goodix Capacitive TouchScreen as /devices/platform/soc/fe205c00.i2c/i2c-6/6-005d/input/input2 Oct 10 11:00:22 motivo systemd-udevd[365]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 10 11:00:22 motivo kernel: Console: switching to colour frame buffer device 160x50 Oct 10 11:00:22 motivo kernel: vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device Oct 10 11:00:22 motivo systemd-udevd[364]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 10 11:00:22 motivo systemd-udevd[363]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 10 11:00:22 motivo kernel: Bluetooth: hci0: BCM: features 0x2f Oct 10 11:00:22 motivo kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Oct 10 11:00:22 motivo kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Oct 10 11:00:22 motivo systemd-udevd[359]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 10 11:00:22 motivo systemd[1]: Created slice system-bthelper.slice. Oct 10 11:00:22 motivo systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 10 11:00:22 motivo systemd[1]: Condition check resulted in Huge Pages File System being skipped. Oct 10 11:00:22 motivo systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 10 11:00:22 motivo systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 10 11:00:22 motivo systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 10 11:00:22 motivo systemd[1]: Created slice system-systemd\x2dbacklight.slice. Oct 10 11:00:22 motivo systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Oct 10 11:00:22 motivo systemd[1]: Reached target Local File Systems. Oct 10 11:00:22 motivo systemd[1]: Started ifup for eth0. Oct 10 11:00:22 motivo systemd[1]: Starting Create Volatile Files and Directories... Oct 10 11:00:22 motivo systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 10 11:00:22 motivo systemd[1]: Starting Raise network interfaces... Oct 10 11:00:22 motivo systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 10 11:00:22 motivo systemd[1]: Starting Preprocess NFS configuration... Oct 10 11:00:22 motivo systemd-udevd[353]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Oct 10 11:00:22 motivo systemd[1]: Starting Load/Save RF Kill Switch Status... Oct 10 11:00:22 motivo systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Oct 10 11:00:22 motivo systemd[1]: nfs-config.service: Succeeded. Oct 10 11:00:22 motivo systemd[1]: Started Preprocess NFS configuration. Oct 10 11:00:22 motivo systemd[1]: Started Load/Save RF Kill Switch Status. Oct 10 11:00:22 motivo systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 10 11:00:22 motivo systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 10 11:00:22 motivo systemd[1]: Reached target NFS client services. Oct 10 11:00:22 motivo kernel: alsactl[540]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Oct 10 11:00:22 motivo systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Oct 10 11:00:22 motivo systemd[1]: Started Create Volatile Files and Directories. Oct 10 11:00:22 motivo systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 10 11:00:22 motivo systemd[1]: Starting RPC bind portmap service... Oct 10 11:00:22 motivo systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Oct 10 11:00:22 motivo systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 10 11:00:22 motivo systemd[1]: Started ifup for wlan0. Oct 10 11:00:23 motivo systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 10 11:00:23 motivo systemd[1]: Started RPC bind portmap service. Oct 10 11:00:23 motivo systemd[1]: Reached target RPC Port Mapper. Oct 10 11:00:23 motivo systemd[1]: Reached target Remote File Systems (Pre). Oct 10 11:00:23 motivo systemd[1]: Reached target Remote File Systems. Oct 10 11:00:23 motivo kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Oct 10 11:00:23 motivo kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 10 11:00:23 motivo kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 10 11:00:23 motivo kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Oct 10 11:00:23 motivo kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 10 11:00:23 motivo sh[570]: eth0: waiting for carrier Oct 10 11:00:23 motivo dhcpcd[607]: eth0: waiting for carrier Oct 10 11:00:23 motivo sh[621]: wlan0=wlan0 Oct 10 11:00:23 motivo systemd[1]: Started Raise network interfaces. Oct 10 11:00:24 motivo systemd[1]: Received SIGRTMIN+20 from PID 366 (plymouthd). Oct 10 11:00:24 motivo systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 10 11:00:24 motivo systemd[1]: Reached target System Initialization. Oct 10 11:00:24 motivo systemd[1]: Started Daily apt download activities. Oct 10 11:00:24 motivo systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 10 11:00:24 motivo systemd[1]: Listening on triggerhappy.socket. Oct 10 11:00:24 motivo systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 10 11:00:24 motivo systemd[1]: Started Daily apt upgrade and clean activities. Oct 10 11:00:24 motivo systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 10 11:00:24 motivo systemd[1]: Reached target Sockets. Oct 10 11:00:24 motivo systemd[1]: Reached target Basic System. Oct 10 11:00:24 motivo systemd[1]: Started volumio-remote-updater.service. Oct 10 11:00:24 motivo systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 10 11:00:24 motivo systemd[1]: Reached target Login Prompts. Oct 10 11:00:24 motivo systemd[1]: Started Volumio Iptables Module. Oct 10 11:00:24 motivo systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 10 11:00:24 motivo systemd[1]: Starting Configure Bluetooth Modems connected by UART... Oct 10 11:00:24 motivo systemd[1]: Starting Wireless Services... Oct 10 11:00:24 motivo systemd[1]: Starting Login Service... Oct 10 11:00:24 motivo systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 10 11:00:24 motivo systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 10 11:00:24 motivo systemd[1]: Starting dhcpcd on all interfaces... Oct 10 11:00:24 motivo systemd[1]: Started D-Bus System Message Bus. Oct 10 11:00:24 motivo systemd[1]: Started Daily man-db regeneration. Oct 10 11:00:24 motivo systemd[1]: Reached target Timers. Oct 10 11:00:24 motivo systemd[1]: Starting triggerhappy global hotkey daemon... Oct 10 11:00:24 motivo systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 10 11:00:24 motivo dhcpcd[714]: Not running dhcpcd because /etc/network/interfaces Oct 10 11:00:24 motivo dhcpcd[714]: defines some interfaces that will use a Oct 10 11:00:24 motivo dhcpcd[714]: DHCP client or static address Oct 10 11:00:24 motivo systemd[1]: Started Volumio Log Rotation Service. Oct 10 11:00:24 motivo systemd[1]: Started Manage Sound Card State (restore and store). Oct 10 11:00:24 motivo alsactl[723]: alsactl 1.1.8 daemon started Oct 10 11:00:24 motivo systemd[1]: Starting Save/Restore Sound Card State... Oct 10 11:00:24 motivo systemd[1]: Starting WPA supplicant... Oct 10 11:00:24 motivo systemd[1]: Started UPnP Renderer front-end to MPD. Oct 10 11:00:24 motivo systemd[1]: hciuart.service: Succeeded. Oct 10 11:00:24 motivo systemd[1]: Started Configure Bluetooth Modems connected by UART. Oct 10 11:00:24 motivo systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Oct 10 11:00:24 motivo systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Oct 10 11:00:24 motivo thd[717]: Unable to parse trigger line: Oct 10 11:00:24 motivo thd[717]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 10 11:00:24 motivo thd[717]: Unable to parse trigger line: Oct 10 11:00:24 motivo thd[717]: Found socket passed from systemd Oct 10 11:00:24 motivo systemd[1]: Failed to start dhcpcd on all interfaces. Oct 10 11:00:24 motivo systemd-logind[713]: New seat seat0. Oct 10 11:00:24 motivo systemd[1]: Starting Raspberry Pi bluetooth helper... Oct 10 11:00:24 motivo avahi-daemon[709]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Oct 10 11:00:24 motivo avahi-daemon[709]: Successfully dropped root privileges. Oct 10 11:00:24 motivo avahi-daemon[709]: avahi-daemon 0.7 starting up. Oct 10 11:00:24 motivo systemd[1]: Started triggerhappy global hotkey daemon. Oct 10 11:00:24 motivo systemd[1]: Started Save/Restore Sound Card State. Oct 10 11:00:24 motivo systemd[1]: Reached target Sound Card. Oct 10 11:00:24 motivo systemd-logind[713]: Watching system buttons on /dev/input/event0 (vc4-hdmi-0) Oct 10 11:00:24 motivo systemd-logind[713]: Watching system buttons on /dev/input/event1 (vc4-hdmi-1) Oct 10 11:00:24 motivo bthelper[728]: Raspberry Pi BDADDR already set Oct 10 11:00:24 motivo volumio-remote-updater[707]: Error: No active session Oct 10 11:00:24 motivo volumio-remote-updater[707]: [2024-10-10 11:00:24] [info] asio async_connect error: system:111 (Connection refused) Oct 10 11:00:24 motivo volumio-remote-updater[707]: [2024-10-10 11:00:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 10 11:00:24 motivo volumio-remote-updater[707]: [2024-10-10 11:00:24] [error] handle_connect error: Underlying Transport Error Oct 10 11:00:25 motivo systemd[1]: Started Login Service. Oct 10 11:00:25 motivo systemd[1]: Started Raspberry Pi bluetooth helper. Oct 10 11:00:25 motivo wpa_supplicant[726]: Successfully initialized wpa_supplicant Oct 10 11:00:25 motivo avahi-daemon[709]: Successfully called chroot(). Oct 10 11:00:25 motivo avahi-daemon[709]: Successfully dropped remaining capabilities. Oct 10 11:00:25 motivo avahi-daemon[709]: No service file found in /etc/avahi/services. Oct 10 11:00:25 motivo avahi-daemon[709]: Network interface enumeration completed. Oct 10 11:00:25 motivo avahi-daemon[709]: Server startup complete. Host name is motivo.local. Local service cookie is 477961042. Oct 10 11:00:25 motivo systemd[1]: Started WPA supplicant. Oct 10 11:00:25 motivo systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 10 11:00:25 motivo systemd[1]: Reached target Network. Oct 10 11:00:25 motivo volumio[727]: Could not open config: /tmp/upmpdcli.conf Oct 10 11:00:25 motivo systemd[1]: Starting OpenBSD Secure Shell server... Oct 10 11:00:25 motivo systemd[1]: Starting Network Time Service... Oct 10 11:00:25 motivo systemd[1]: Starting Permit User Sessions... Oct 10 11:00:25 motivo systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 10 11:00:25 motivo systemd[1]: Reached target Network is Online. Oct 10 11:00:25 motivo systemd[1]: Starting Daily apt download activities... Oct 10 11:00:25 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:25 motivo systemd[1]: Starting /etc/rc.local Compatibility... Oct 10 11:00:25 motivo systemd[1]: Starting LSB: Brings up/down network automatically... Oct 10 11:00:25 motivo systemd[1]: Starting Samba NMB Daemon... Oct 10 11:00:25 motivo systemd[1]: Starting Bluetooth service... Oct 10 11:00:25 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 10 11:00:25 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 10 11:00:25 motivo systemd[1]: Started Permit User Sessions. Oct 10 11:00:25 motivo systemd[1]: Started /etc/rc.local Compatibility. Oct 10 11:00:25 motivo systemd[1]: iptables.service: Succeeded. Oct 10 11:00:25 motivo ntpd[765]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 10 11:00:25 motivo ntpd[765]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Oct 10 11:00:25 motivo systemd[1]: Started Network Time Service. Oct 10 11:00:25 motivo ifplugd(eth0)[817]: ifplugd 0.28 initializing. Oct 10 11:00:25 motivo ifplugd(eth0)[817]: Using interface eth0/2C:CF:67:3F:26:99 with driver (version: 6.6.30-v8+) Oct 10 11:00:25 motivo ifplugd(eth0)[817]: Using detection mode: SIOCETHTOOL Oct 10 11:00:25 motivo ifplugd(eth0)[817]: Initialization complete, link beat not detected. Oct 10 11:00:25 motivo sshd[807]: Server listening on 0.0.0.0 port 22. Oct 10 11:00:25 motivo loadcpufreq[719]: Loading cpufreq kernel modules...done (none). Oct 10 11:00:25 motivo systemd[1]: Started OpenBSD Secure Shell server. Oct 10 11:00:25 motivo sshd[807]: Server listening on :: port 22. Oct 10 11:00:25 motivo systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 10 11:00:25 motivo systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 10 11:00:25 motivo ifplugd[772]: Network Interface Plugging Daemon...start eth0...done. Oct 10 11:00:25 motivo systemd[1]: Started LSB: Brings up/down network automatically. Oct 10 11:00:25 motivo bluetoothd[778]: Bluetooth daemon 5.50 Oct 10 11:00:25 motivo ntpd[813]: proto: precision = 2.370 usec (-19) Oct 10 11:00:25 motivo bluetoothd[778]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 10 11:00:26 motivo systemd[1]: Started Bluetooth service. Oct 10 11:00:26 motivo systemd[1]: Reached target Bluetooth. Oct 10 11:00:26 motivo bluetoothd[778]: Starting SDP server Oct 10 11:00:26 motivo bluetoothd[778]: Excluding (cli) sap Oct 10 11:00:26 motivo ntpd[813]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 10 11:00:26 motivo ntpd[813]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 10 11:00:26 motivo ntpd[813]: Listen and drop on 0 v6wildcard [::]:123 Oct 10 11:00:26 motivo ntpd[813]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 10 11:00:26 motivo ntpd[813]: Listen normally on 2 lo 127.0.0.1:123 Oct 10 11:00:26 motivo ntpd[813]: Listening on routing socket on fd #19 for interface updates Oct 10 11:00:26 motivo ntpd[813]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 10 11:00:26 motivo ntpd[813]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 10 11:00:26 motivo kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 10 11:00:26 motivo kernel: Bluetooth: BNEP filters: protocol multicast Oct 10 11:00:26 motivo kernel: Bluetooth: BNEP socket layer initialized Oct 10 11:00:26 motivo bluetoothd[778]: Bluetooth management interface 1.22 initialized Oct 10 11:00:26 motivo dbus-daemon[716]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=778 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 10 11:00:26 motivo kernel: Bluetooth: MGMT ver 1.22 Oct 10 11:00:26 motivo cpufrequtils[818]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 10 11:00:26 motivo bluetoothd[778]: Failed to set privacy: Rejected (0x0b) Oct 10 11:00:26 motivo haveged[618]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Oct 10 11:00:26 motivo haveged[618]: haveged: cpu: (VC); data: 32K (V); inst: 48K (V); idx: 30/40; sz: 47828/63488 Oct 10 11:00:26 motivo haveged[618]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00139 Oct 10 11:00:26 motivo haveged[618]: haveged: fills: 0, generated: 0 Oct 10 11:00:26 motivo systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 10 11:00:26 motivo systemd[1]: Starting Hostname Service... Oct 10 11:00:26 motivo dbus-daemon[716]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 10 11:00:26 motivo systemd[1]: Started Hostname Service. Oct 10 11:00:26 motivo nmbd[783]: [2024/10/10 11:00:26.711264, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 10 11:00:26 motivo nmbd[783]: started asyncdns process 861 Oct 10 11:00:26 motivo nmbd[783]: [2024/10/10 11:00:26.714369, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 10 11:00:26 motivo nmbd[783]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 10 11:00:26 motivo nmbd[783]: [2024/10/10 11:00:26.714541, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 10 11:00:26 motivo nmbd[783]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 10 11:00:26 motivo ntpd[813]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 10 11:00:27 motivo wireless.js[711]: Cleaning previous... Oct 10 11:00:27 motivo systemd[1]: apt-daily.service: Succeeded. Oct 10 11:00:27 motivo systemd[1]: Started Daily apt download activities. Oct 10 11:00:27 motivo sudo[898]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 10 11:00:27 motivo sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:27 motivo sudo[898]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:27 motivo sudo[900]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 10 11:00:27 motivo sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:27 motivo kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Oct 10 11:00:27 motivo sh[570]: eth0: carrier acquired Oct 10 11:00:27 motivo dhcpcd[607]: eth0: carrier acquired Oct 10 11:00:27 motivo sudo[900]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:27 motivo sh[570]: DUID 00:01:00:01:2e:31:39:41:2c:cf:67:3f:26:99 Oct 10 11:00:27 motivo sh[570]: eth0: IAID 67:3f:26:99 Oct 10 11:00:27 motivo dhcpcd[607]: DUID 00:01:00:01:2e:31:39:41:2c:cf:67:3f:26:99 Oct 10 11:00:27 motivo dhcpcd[607]: eth0: IAID 67:3f:26:99 Oct 10 11:00:27 motivo sh[570]: eth0: adding address fe80::cfd0:8bb7:49ee:e932 Oct 10 11:00:27 motivo sh[570]: ipv6_addaddr1: Permission denied Oct 10 11:00:27 motivo dhcpcd[607]: eth0: adding address fe80::cfd0:8bb7:49ee:e932 Oct 10 11:00:27 motivo dhcpcd[607]: ipv6_addaddr1: Permission denied Oct 10 11:00:27 motivo wireless.js[711]: Stopped aP Oct 10 11:00:27 motivo sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 10 11:00:27 motivo systemd[1]: systemd-rfkill.service: Succeeded. Oct 10 11:00:27 motivo sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:27 motivo kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 10 11:00:27 motivo sudo[908]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:27 motivo sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 10 11:00:27 motivo sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:27 motivo ntpd[813]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 10 11:00:27 motivo sudo[910]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:27 motivo sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 10 11:00:27 motivo sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:27 motivo sudo[917]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:27 motivo sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 10 11:00:27 motivo sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:27 motivo ifplugd(eth0)[817]: Link beat detected. Oct 10 11:00:27 motivo sh[570]: eth0: soliciting a DHCP lease Oct 10 11:00:27 motivo dhcpcd[607]: eth0: soliciting a DHCP lease Oct 10 11:00:28 motivo ifplugd(eth0)[817]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 10 11:00:28 motivo ifplugd(eth0)[817]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Oct 10 11:00:28 motivo sh[570]: eth0: soliciting an IPv6 router Oct 10 11:00:28 motivo dhcpcd[607]: eth0: soliciting an IPv6 router Oct 10 11:00:28 motivo ntpd[813]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 10 11:00:29 motivo sh[570]: eth0: offered 192.168.1.135 from 192.168.1.1 `IX2215_twcsg' Oct 10 11:00:29 motivo dhcpcd[607]: eth0: offered 192.168.1.135 from 192.168.1.1 `IX2215_twcsg' Oct 10 11:00:29 motivo sh[570]: eth0: probing address 192.168.1.135/24 Oct 10 11:00:29 motivo dhcpcd[607]: eth0: probing address 192.168.1.135/24 Oct 10 11:00:29 motivo volumio-remote-updater[707]: [2024-10-10 11:00:29] [info] asio async_connect error: system:111 (Connection refused) Oct 10 11:00:29 motivo volumio-remote-updater[707]: [2024-10-10 11:00:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 10 11:00:29 motivo volumio-remote-updater[707]: [2024-10-10 11:00:29] [error] handle_connect error: Underlying Transport Error Oct 10 11:00:29 motivo ntpd[813]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 10 11:00:29 motivo bthelper[728]: Changing power off succeeded Oct 10 11:00:30 motivo bthelper[728]: [CHG] Controller 2C:CF:67:3F:26:9B Class: 0x0000041c Oct 10 11:00:30 motivo bthelper[728]: Changing power on succeeded Oct 10 11:00:30 motivo bthelper[728]: [CHG] Controller 2C:CF:67:3F:26:9B Powered: yes Oct 10 11:00:30 motivo sudo[919]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:30 motivo wireless.js[711]: SETTING APPROPRIATE REG DOMAIN: JP Oct 10 11:00:30 motivo sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 10 11:00:30 motivo sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:30 motivo sudo[934]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:30 motivo sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Oct 10 11:00:30 motivo sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:30 motivo sudo[936]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:30 motivo wireless.js[711]: SUCCESSFULLY SET NEW REGDOMAIN: JP Oct 10 11:00:30 motivo sudo[943]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 10 11:00:30 motivo sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:30 motivo sudo[943]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:30 motivo sudo[945]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 10 11:00:30 motivo sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:31 motivo sudo[945]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:31 motivo avahi-daemon[709]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 10 11:00:31 motivo avahi-daemon[709]: New relevant interface wlan0.IPv4 for mDNS. Oct 10 11:00:31 motivo avahi-daemon[709]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Oct 10 11:00:31 motivo kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 10 11:00:31 motivo systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 10 11:00:31 motivo systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Oct 10 11:00:31 motivo dnsmasq[952]: dnsmasq: syntax check OK. Oct 10 11:00:31 motivo hostapd[951]: Configuration file: /etc/hostapd/hostapd.conf Oct 10 11:00:31 motivo hostapd[951]: wlan0: Could not connect to kernel driver Oct 10 11:00:31 motivo hostapd[951]: Using interface wlan0 with hwaddr 2c:cf:67:3f:26:9a and ssid "Volumio-C73E9" Oct 10 11:00:31 motivo dnsmasq[958]: started, version 2.80 cachesize 150 Oct 10 11:00:31 motivo dnsmasq[958]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Oct 10 11:00:31 motivo dnsmasq-dhcp[958]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Oct 10 11:00:31 motivo dnsmasq[958]: reading /etc/resolv.conf Oct 10 11:00:31 motivo dnsmasq[958]: using nameserver 208.67.222.222#53 Oct 10 11:00:31 motivo dnsmasq[958]: using nameserver 208.67.220.220#53 Oct 10 11:00:31 motivo dnsmasq[958]: read /etc/hosts - 5 addresses Oct 10 11:00:31 motivo systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Oct 10 11:00:31 motivo systemd[1]: Reached target Host and Network Name Lookups. Oct 10 11:00:31 motivo hostapd[951]: wlan0: interface state UNINITIALIZED->ENABLED Oct 10 11:00:31 motivo hostapd[951]: wlan0: AP-ENABLED Oct 10 11:00:31 motivo systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 10 11:00:31 motivo iwconfig[966]: Error for wireless request "Set Power Management" (8B2C) : Oct 10 11:00:31 motivo iwconfig[966]: SET failed on device wlan0 ; Invalid argument. Oct 10 11:00:31 motivo systemd[1]: wireless.service: Succeeded. Oct 10 11:00:31 motivo systemd[1]: Started Wireless Services. Oct 10 11:00:31 motivo systemd[1]: Started Volumio Backend Module. Oct 10 11:00:31 motivo systemd[1]: Started Volumio Cpu Tweaker. Oct 10 11:00:31 motivo systemd[1]: Started Start Volumio Kiosk. Oct 10 11:00:31 motivo systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 10 11:00:31 motivo startkiosk.sh[969]: Starting Volumio Kiosk with arguments for cursor display Oct 10 11:00:31 motivo systemd[1]: Starting Hold until boot process finishes up... Oct 10 11:00:31 motivo systemd[1]: Received SIGRTMIN+21 from PID 366 (plymouthd). Oct 10 11:00:31 motivo volumio-cpu-tweak[968]: Setting RT Priority for mpd Oct 10 11:00:31 motivo volumio-cpu-tweak[968]: pid 35's current scheduling policy: SCHED_OTHER Oct 10 11:00:31 motivo volumio-cpu-tweak[968]: pid 35's current scheduling priority: 0 Oct 10 11:00:31 motivo volumio-cpu-tweak[968]: Setting MPD Affinity Oct 10 11:00:31 motivo volumio-cpu-tweak[968]: pid 3's current affinity mask: f Oct 10 11:00:31 motivo volumio-cpu-tweak[968]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 10 11:00:31 motivo volumio-cpu-tweak[968]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Oct 10 11:00:31 motivo systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 10 11:00:31 motivo systemd[1]: Received SIGRTMIN+21 from PID 366 (plymouthd). Oct 10 11:00:31 motivo systemd[1]: Started Hold until boot process finishes up. Oct 10 11:00:31 motivo systemd[1]: Started Terminate Plymouth Boot Screen. Oct 10 11:00:31 motivo startkiosk.sh[969]: X.Org X Server 1.20.4 Oct 10 11:00:31 motivo startkiosk.sh[969]: X Protocol Version 11, Revision 0 Oct 10 11:00:31 motivo startkiosk.sh[969]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Oct 10 11:00:31 motivo startkiosk.sh[969]: Current Operating System: Linux motivo 6.6.30-v8+ #1763 SMP PREEMPT Mon May 13 17:50:31 BST 2024 aarch64 Oct 10 11:00:31 motivo startkiosk.sh[969]: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0 snd_bcm2835.enable_hdmi=0 smsc95xx.macaddr=2C:CF:67:3F:26:99 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=8fdfc54a-1668-49f2-9cf8-5aaf96c2e081 imgfile=/volumio_current.sqsh bootpart=UUID=2179-32B9 datapart=UUID=ec7e8edf-ddb6-4ca6-b032-46609347f291 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=1 loglevel=0 nodebug use_kmsg=no Oct 10 11:00:31 motivo startkiosk.sh[969]: Build Date: 04 April 2023 07:50:56AM Oct 10 11:00:31 motivo startkiosk.sh[969]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Oct 10 11:00:31 motivo startkiosk.sh[969]: Current version of pixman: 0.36.0 Oct 10 11:00:31 motivo startkiosk.sh[969]: Before reporting problems, check http://wiki.x.org Oct 10 11:00:31 motivo startkiosk.sh[969]: to make sure that you have the latest version. Oct 10 11:00:31 motivo startkiosk.sh[969]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 10 11:00:31 motivo startkiosk.sh[969]: (++) from command line, (!!) notice, (II) informational, Oct 10 11:00:31 motivo startkiosk.sh[969]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 10 11:00:31 motivo startkiosk.sh[969]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Oct 10 11:00:31 2024 Oct 10 11:00:31 motivo startkiosk.sh[969]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 10 11:00:31 motivo startkiosk.sh[969]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 10 11:00:31 motivo nmbd[783]: [2024/10/10 11:00:31.722297, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 10 11:00:31 motivo nmbd[783]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 10 11:00:31 motivo systemd[1]: Started Samba NMB Daemon. Oct 10 11:00:31 motivo systemd[1]: Starting Samba Winbind Daemon... Oct 10 11:00:32 motivo winbindd[1012]: [2024/10/10 11:00:32.006020, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 10 11:00:32 motivo winbindd[1012]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 10 11:00:32 motivo winbindd[1012]: [2024/10/10 11:00:32.020461, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 10 11:00:32 motivo winbindd[1012]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 10 11:00:32 motivo systemd[1]: Started Samba Winbind Daemon. Oct 10 11:00:32 motivo systemd[1]: Starting Samba SMB Daemon... Oct 10 11:00:32 motivo smbd[1017]: [2024/10/10 11:00:32.430091, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 10 11:00:32 motivo smbd[1017]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 10 11:00:32 motivo systemd[1]: Started Samba SMB Daemon. Oct 10 11:00:32 motivo systemd[1]: Reached target Multi-User System. Oct 10 11:00:32 motivo systemd[1]: Reached target Graphical Interface. Oct 10 11:00:32 motivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 10 11:00:32 motivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 10 11:00:32 motivo systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 10 11:00:32 motivo systemd[1]: Startup finished in 13.132s (kernel) + 14.827s (userspace) = 27.960s. Oct 10 11:00:32 motivo ntpd[813]: Listen normally on 3 wlan0 192.168.211.1:123 Oct 10 11:00:32 motivo ntpd[813]: new interface(s) found: waking up resolver Oct 10 11:00:32 motivo kernel: broken atomic modeset userspace detected, disabling atomic Oct 10 11:00:33 motivo startkiosk.sh[969]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Oct 10 11:00:33 motivo volumio[967]: info: ------------------------------------------- Oct 10 11:00:33 motivo volumio[967]: info: ----- Volumio3 ---- Oct 10 11:00:33 motivo volumio[967]: info: ------------------------------------------- Oct 10 11:00:33 motivo volumio[967]: info: ----- System startup ---- Oct 10 11:00:33 motivo volumio[967]: info: ------------------------------------------- Oct 10 11:00:34 motivo volumio[967]: info: MYVOLUMIO Environment detected Oct 10 11:00:34 motivo sh[570]: eth0: leased 192.168.1.135 for 14400 seconds Oct 10 11:00:34 motivo sh[570]: eth0: adding route to 192.168.1.0/24 Oct 10 11:00:34 motivo sh[570]: eth0: adding default route via 192.168.1.1 Oct 10 11:00:34 motivo dhcpcd[607]: eth0: leased 192.168.1.135 for 14400 seconds Oct 10 11:00:34 motivo dhcpcd[607]: eth0: adding route to 192.168.1.0/24 Oct 10 11:00:34 motivo dhcpcd[607]: eth0: adding default route via 192.168.1.1 Oct 10 11:00:34 motivo avahi-daemon[709]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.135. Oct 10 11:00:34 motivo avahi-daemon[709]: New relevant interface eth0.IPv4 for mDNS. Oct 10 11:00:34 motivo avahi-daemon[709]: Registering new address record for 192.168.1.135 on eth0.IPv4. Oct 10 11:00:34 motivo dnsmasq[958]: reading /etc/resolv.conf Oct 10 11:00:34 motivo dnsmasq[958]: using nameserver 192.168.1.1#53 Oct 10 11:00:34 motivo dnsmasq[958]: using nameserver 208.67.222.222#53 Oct 10 11:00:34 motivo dnsmasq[958]: using nameserver 208.67.220.220#53 Oct 10 11:00:34 motivo sh[570]: forked to background, child pid 1100 Oct 10 11:00:34 motivo dhcpcd[607]: forked to background, child pid 1100 Oct 10 11:00:34 motivo volumio[967]: info: Plugin folders cleanup Oct 10 11:00:34 motivo volumio[967]: info: Scanning into folder /volumio/app/plugins/ Oct 10 11:00:34 motivo volumio[967]: info: Scanning category audio_interface Oct 10 11:00:34 motivo volumio[967]: info: Scanning category miscellanea Oct 10 11:00:34 motivo volumio[967]: info: Scanning category music_service Oct 10 11:00:34 motivo volumio[967]: info: Scanning category plugins.json Oct 10 11:00:34 motivo volumio[967]: info: Scanning category system_controller Oct 10 11:00:34 motivo volumio[967]: info: Scanning category user_interface Oct 10 11:00:34 motivo volumio[967]: info: Scanning into folder /data/plugins/ Oct 10 11:00:34 motivo volumio[967]: info: Plugin folders cleanup completed Oct 10 11:00:34 motivo volumio[967]: info: ------------------------------------------- Oct 10 11:00:34 motivo volumio[967]: info: ----- Core plugins startup ---- Oct 10 11:00:34 motivo volumio[967]: info: ------------------------------------------- Oct 10 11:00:34 motivo volumio[967]: info: Loading plugins from folder /volumio/app/plugins/ Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin bluetooth to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin multiroom to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin upnp to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin metavolumio to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin cd_controller to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin raat to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 10 11:00:34 motivo volumio[967]: info: Loading plugins from folder /data/plugins/ Oct 10 11:00:34 motivo volumio[967]: info: Loading plugin "system"... Oct 10 11:00:34 motivo volumio[967]: info: Loading plugin "appearance"... Oct 10 11:00:34 motivo ifplugd(eth0)[817]: client: ifup: interface eth0 already configured Oct 10 11:00:34 motivo sh[570]: eth0=eth0 Oct 10 11:00:34 motivo ifplugd(eth0)[817]: Program executed successfully. Oct 10 11:00:35 motivo ntpd[813]: Listen normally on 4 eth0 192.168.1.135:123 Oct 10 11:00:35 motivo ntpd[813]: new interface(s) found: waking up resolver Oct 10 11:00:35 motivo volumio[967]: info: Loading plugin "network"... Oct 10 11:00:35 motivo volumio[967]: info: Refreshing Cached IP Addresses Oct 10 11:00:35 motivo sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 11:00:35 motivo sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:35 motivo volumio[967]: info: Loading plugin "services"... Oct 10 11:00:35 motivo sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 11:00:35 motivo sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:35 motivo sudo[1137]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:35 motivo volumio[967]: info: Loading plugin "alsa_controller"... Oct 10 11:00:35 motivo sudo[1139]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:35 motivo sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 10 11:00:35 motivo sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:36 motivo volumio[967]: warn: Unable to locate the audio output device Analog + I2S + Headphones. Please configure a valid output device. Oct 10 11:00:36 motivo volumio[967]: Invalid card number. Oct 10 11:00:36 motivo volumio[967]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Oct 10 11:00:36 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 11:00:36 motivo volumio[967]: Invalid card number. Oct 10 11:00:36 motivo volumio[967]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 10 11:00:36 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 11:00:36 motivo volumio[967]: info: Loading plugin "wizard"... Oct 10 11:00:36 motivo volumio[967]: info: Loading plugin "networkfs"... Oct 10 11:00:36 motivo sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=suganuma,password=!!11Topwing,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.11/share\\サポート_リペア_開発_音源関連\\TEST_試聴音源\\512GBライブラリ /mnt/NAS/Synology Oct 10 11:00:36 motivo sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:36 motivo volumio[967]: info: Starting Udev Watcher for removable devices Oct 10 11:00:36 motivo sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.133/contents /mnt/NAS/Soundgenic1 Oct 10 11:00:36 motivo sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:36 motivo sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.134/contents /mnt/NAS/Soundgenic Oct 10 11:00:36 motivo sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:36 motivo volumio[967]: info: Ignoring mount for partition: boot Oct 10 11:00:36 motivo volumio[967]: info: Ignoring mount for partition: volumio Oct 10 11:00:36 motivo volumio[967]: info: Ignoring mount for partition: volumio_data Oct 10 11:00:36 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 11:00:36 motivo volumio[967]: info: Loading plugin "volumio_command_line_client"... Oct 10 11:00:36 motivo volumio[967]: info: Loading plugin "upnp"... Oct 10 11:00:36 motivo volumio[967]: info: [1728525636238] Starting Upmpd Daemon Oct 10 11:00:36 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 11:00:36 motivo volumio[967]: info: Loading plugin "my_music"... Oct 10 11:00:36 motivo volumio[967]: info: Loading plugin "mpd"... Oct 10 11:00:36 motivo kernel: Key type cifs.spnego registered Oct 10 11:00:36 motivo kernel: Key type cifs.idmap registered Oct 10 11:00:36 motivo kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 10 11:00:36 motivo kernel: CIFS: Attempting to mount //192.168.1.11/share/サポート_リペア_開発_音源関連/TEST_試聴音源/512GBライブラリ Oct 10 11:00:36 motivo volumio[967]: info: Loading plugin "upnp_browser"... Oct 10 11:00:36 motivo kernel: CIFS: Attempting to mount //192.168.1.134/contents Oct 10 11:00:36 motivo sudo[1156]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:36 motivo volumio[967]: info: Loading plugin "bluetooth"... Oct 10 11:00:36 motivo volumio[967]: info: [1728525636988] Starting BluetoothController Oct 10 11:00:37 motivo volumio[967]: info: Loading plugin "alarm-clock"... Oct 10 11:00:37 motivo volumio[967]: info: Loading plugin "manifestui"... Oct 10 11:00:37 motivo volumio-remote-updater[707]: [2024-10-10 11:00:37] [connect] Successful connection Oct 10 11:00:37 motivo kernel: CIFS: Attempting to mount //192.168.1.133/contents Oct 10 11:00:37 motivo sudo[1161]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:37 motivo volumio[967]: info: Loading plugin "metavolumio"... Oct 10 11:00:38 motivo volumio[967]: info: Loading plugin "airplay_emulation"... Oct 10 11:00:38 motivo volumio[967]: info: Starting Shairport Sync Oct 10 11:00:38 motivo volumio[967]: info: Loading plugin "cd_controller"... Oct 10 11:00:38 motivo volumio[967]: info: Loading plugin "last_100"... Oct 10 11:00:38 motivo volumio[967]: info: Loading plugin "raat"... Oct 10 11:00:38 motivo volumio[967]: info: RAAT Plugin loaded Oct 10 11:00:38 motivo volumio[967]: info: Adding restartRAATSocket REST API Endpoint Oct 10 11:00:38 motivo volumio[967]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 10 11:00:38 motivo volumio[967]: info: Loading plugin "streaming_services"... Oct 10 11:00:39 motivo sudo[1142]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:40 motivo volumio[967]: info: Starting Streaming Service Transparent Proxy Oct 10 11:00:40 motivo volumio[967]: info: Loading plugin "tidalconnect"... Oct 10 11:00:40 motivo volumio[967]: info: Loading plugin "webradio"... Oct 10 11:00:40 motivo volumio[967]: info: Loading plugin "i2s_dacs"... Oct 10 11:00:40 motivo volumio[967]: info: I2S DAC not set, start Auto-detection Oct 10 11:00:40 motivo volumio[967]: info: Loading plugin "volumiodiscovery"... Oct 10 11:00:40 motivo volumio[967]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 11:00:40 motivo volumio[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 11:00:40 motivo volumio[967]: *** WARNING *** For more information see Oct 10 11:00:40 motivo volumio[967]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 11:00:40 motivo volumio[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 11:00:40 motivo volumio[967]: *** WARNING *** For more information see Oct 10 11:00:40 motivo node[967]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 11:00:40 motivo node[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 11:00:40 motivo node[967]: *** WARNING *** For more information see Oct 10 11:00:40 motivo node[967]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 11:00:40 motivo node[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 11:00:40 motivo node[967]: *** WARNING *** For more information see Oct 10 11:00:40 motivo volumio[967]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 10 11:00:40 motivo volumio[967]: info: Discovery: Started advertising with name: Motivo Oct 10 11:00:40 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 11:00:40 motivo volumio[967]: info: Loading plugin "multiroom"... Oct 10 11:00:41 motivo volumio[967]: STREAMING PROXY: Starting server on port 3245 Oct 10 11:00:41 motivo volumio[967]: Node JS runtime: 14 Oct 10 11:00:43 motivo volumio[967]: info: Applying required configuration parameters for plugin multiroom Oct 10 11:00:43 motivo sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 10 11:00:43 motivo sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:44 motivo sudo[1338]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:44 motivo volumio[967]: info: MRS: MultiRoom plugin initialized Oct 10 11:00:44 motivo volumio[967]: info: MRS: STOPPING SNAPCLIENT Oct 10 11:00:44 motivo volumio[967]: info: MRS: Snap server stop Oct 10 11:00:44 motivo sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 10 11:00:44 motivo sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:44 motivo volumio[967]: info: MRS: STOPPING volumioStreaming Oct 10 11:00:44 motivo sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 10 11:00:44 motivo sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:44 motivo sudo[1359]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:44 motivo sudo[1363]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:44 motivo sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 10 11:00:44 motivo sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:44 motivo volumio[967]: info: Loading plugin "outputs"... Oct 10 11:00:44 motivo sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 10 11:00:44 motivo sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:44 motivo sudo[1370]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:44 motivo volumio[967]: info: Loading plugin "albumart"... Oct 10 11:00:44 motivo sudo[1365]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:44 motivo volumio[967]: info: Plugin example_plugin is not enabled Oct 10 11:00:44 motivo volumio[967]: info: Loading plugin "hi_res_audio"... Oct 10 11:00:44 motivo volumio[967]: Forking 3 albumart workers Oct 10 11:00:45 motivo volumio[967]: Starting albumart workers Oct 10 11:00:45 motivo volumio[967]: Starting albumart workers Oct 10 11:00:46 motivo volumio[967]: info: Applying required configuration parameters for plugin hi_res_audio Oct 10 11:00:46 motivo volumio[967]: info: Loading plugin "inputs"... Oct 10 11:00:46 motivo volumio[967]: Starting albumart workers Oct 10 11:00:46 motivo volumio[967]: info: Loading plugin "qobuz"... Oct 10 11:00:47 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 10 11:00:47 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 10 11:00:47 motivo sudo[1158]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:48 motivo volumio[967]: info: Loading plugin "smart_inputs"... Oct 10 11:00:48 motivo volumio[967]: info: Loading plugin "tidal"... Oct 10 11:00:49 motivo volumio[967]: info: Loading plugin "motivocontrol"... Oct 10 11:00:49 motivo volumio[967]: info: Initializing System Ready GPIO for kernel version: 6.6.30-v8+ Oct 10 11:00:49 motivo volumio[967]: info: Adding this device properties Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 10 11:00:49 motivo volumio[967]: info: Setting Additional Device Volumio Properties: [object Object] Oct 10 11:00:49 motivo volumio[967]: info: Checking For Motivo Hardware Oct 10 11:00:49 motivo volumio[967]: info: Loading plugin "updater_comm"... Oct 10 11:00:49 motivo volumio[967]: info: Plugin mpdemulation is not enabled Oct 10 11:00:49 motivo volumio[967]: info: Loading plugin "rest_api"... Oct 10 11:00:49 motivo volumio[967]: info: Loading plugin "websocket"... Oct 10 11:00:49 motivo volumio[967]: info: Starting Socket.io Server version 2.3.0 Oct 10 11:00:49 motivo volumio[967]: info: Loading i18n strings for locale ja Oct 10 11:00:49 motivo volumio[967]: Updating browse sources language Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::initPlayerControls Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:00:49 motivo volumio[967]: Express server listening on port 3000 Oct 10 11:00:49 motivo volumio[967]: [Metrics] WebUI: 16s 717.32ms Oct 10 11:00:49 motivo volumio[967]: info: CoreStateMachine::resetVolumioState Oct 10 11:00:49 motivo volumio[967]: info: CoreStateMachine::getcurrentVolume Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 10 11:00:49 motivo volumio[967]: info: CoreStateMachine::getcurrentVolume Oct 10 11:00:49 motivo volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 11:00:49 motivo sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 11:00:49 motivo sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:49 motivo sudo[1453]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:49 motivo sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 11:00:49 motivo sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:49 motivo sudo[1455]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:49 motivo volumio[967]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 10 11:00:49 motivo volumio[967]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 10 11:00:49 motivo volumio[967]: info: Motivo Hardware found Oct 10 11:00:49 motivo volumio[967]: info: MRS: Removed streaming files Oct 10 11:00:49 motivo volumio[967]: info: MRS: volumioStreaming STOPPED Oct 10 11:00:49 motivo volumio[967]: info: MRS: SNAPSERVER STOPPED Oct 10 11:00:49 motivo volumio[967]: info: MRS: SNAPCLIENT STOPPED Oct 10 11:00:49 motivo volumio[967]: info: Cannot mount NAS Soundgenic1 at system boot, trial number 1 ,retrying in 5 seconds Oct 10 11:00:50 motivo volumio[967]: info: VolumeController:: Volume=undefined Mute =false Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:50 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::updateTrackBlock Oct 10 11:00:50 motivo volumio[967]: info: CorePlayQueue::getTrackBlock Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 11:00:50 motivo volumio[967]: info: VolumeController:: Volume=undefined Mute =false Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:50 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::updateTrackBlock Oct 10 11:00:50 motivo volumio[967]: info: CorePlayQueue::getTrackBlock Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 11:00:50 motivo volumio[967]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 10 11:00:50 motivo volumio[967]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 10 11:00:50 motivo volumio[967]: info: Reloading queue from file Oct 10 11:00:50 motivo volumio[967]: info: VolumeController:: Volume=undefined Mute =false Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:50 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:50 motivo volumio[967]: info: VolumeController:: Volume=undefined Mute =false Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:50 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::setRepeat null single undefined Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:50 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::setRandom null Oct 10 11:00:50 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:50 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:50 motivo volumio[967]: info: Setting Device type: Raspberry PI Oct 10 11:00:50 motivo volumio[967]: info: Completed loading Core Plugins Oct 10 11:00:50 motivo volumio[967]: info: Preparing to generate the ALSA configuration file Oct 10 11:00:50 motivo sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 10 11:00:50 motivo sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo volumio[967]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 10 11:00:50 motivo volumio[967]: info: Reading ALSA contributions from plugins. Oct 10 11:00:50 motivo volumio[967]: info: Asound.conf file unchanged, so no further update is needed Oct 10 11:00:50 motivo volumio[967]: info: Output device has changed, restarting MPD Oct 10 11:00:50 motivo systemd[1]: Started UPnP Renderer front-end to MPD. Oct 10 11:00:50 motivo sudo[1472]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:50 motivo volumio[967]: info: Output device has changed, restarting Shairport Sync Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:50 motivo sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 11:00:50 motivo sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 11:00:50 motivo sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo sudo[1477]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:50 motivo volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 11:00:50 motivo volumio[967]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: ___________ START PLUGINS ___________ Oct 10 11:00:50 motivo systemd[1]: Listening on mpd.socket. Oct 10 11:00:50 motivo systemd[1]: Starting Music Player Daemon... Oct 10 11:00:50 motivo volumio[967]: info: ControllerMpd::onStart: Initializing MPD Oct 10 11:00:50 motivo volumio[967]: info: Creating MPD Configuration file Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 11:00:50 motivo sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 11:00:50 motivo sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo volumio[967]: info: [1728525650646] CoreMusicLibrary::Adding element メディアサーバー Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:00:50 motivo sudo[1490]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:50 motivo sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 11:00:50 motivo sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo sudo[1488]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 11:00:50 motivo sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo sudo[1488]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:50 motivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 10 11:00:50 motivo systemd[1]: mpd.service: Succeeded. Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 11:00:50 motivo systemd[1]: Stopped Music Player Daemon. Oct 10 11:00:50 motivo volumio[967]: info: Adding METAVOLUMIO REST API Endpoints Oct 10 11:00:50 motivo volumio[967]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 10 11:00:50 motivo volumio[967]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 10 11:00:50 motivo volumio[967]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 10 11:00:50 motivo volumio[967]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 10 11:00:50 motivo systemd[1]: Starting Music Player Daemon... Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:50 motivo sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Motivo Oct 10 11:00:50 motivo sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:50 motivo sudo[1498]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:50 motivo sudo[1496]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 11:00:50 motivo sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 11:00:50 motivo volumio[967]: info: Preparing CD Folders Oct 10 11:00:50 motivo volumio[967]: info: Adding CD REST API Endpoints Oct 10 11:00:50 motivo volumio[967]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 10 11:00:50 motivo volumio[967]: info: Starting UDEV Watcher for CD Oct 10 11:00:50 motivo sudo[1496]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 10 11:00:50 motivo volumio[967]: info: Detecting CD presence with UDEV Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 10 11:00:50 motivo sudo[1496]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 11:00:50 motivo volumio[967]: info: [1728525650834] CoreMusicLibrary::Adding element Last_100 Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:00:50 motivo volumio[967]: info: Starting RAAT Plugin Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 10 11:00:50 motivo volumio[967]: info: Additional UI Settings Added for plugin music_service/raat Oct 10 11:00:50 motivo volumio[967]: info: Registering DSP Elements listener and retrieving current ones Oct 10 11:00:50 motivo volumio[967]: info: Additional DSP elements updated Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: Updating RAAT Signal Path Oct 10 11:00:50 motivo volumio[967]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:00:50 motivo volumio[967]: info: Not Reporting Auto name since its the default one Oct 10 11:00:50 motivo volumio[967]: info: Streaming services startup Oct 10 11:00:50 motivo volumio[967]: info: Starting Streaming Daemon Oct 10 11:00:50 motivo sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 10 11:00:50 motivo sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:50 motivo sudo[1515]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 11:00:50 motivo volumio[967]: info: [1728525650999] CoreMusicLibrary::Adding element Webradio Oct 10 11:00:50 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 11:00:51 motivo volumio[967]: info: Initializing BBC Radios Oct 10 11:00:51 motivo sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 10 11:00:51 motivo sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:51 motivo systemd[1]: systemd-fsckd.service: Succeeded. Oct 10 11:00:51 motivo sudo[1522]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 10 11:00:51 motivo volumio[967]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: error: Hi Res Audio Failed Login: Missing Login Data Oct 10 11:00:51 motivo volumio[967]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 10 11:00:51 motivo volumio[967]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 10 11:00:51 motivo volumio[967]: info: Initializing Serial Communication on port /dev/ttyAMA4 Oct 10 11:00:51 motivo volumio[967]: info: Touch Event Listener Process Starting Oct 10 11:00:51 motivo kernel: uart-pl011 fe201800.serial: no DMA platform data Oct 10 11:00:51 motivo volumio[967]: info: Refreshing QOBUZ token Oct 10 11:00:51 motivo sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 10 11:00:51 motivo volumio[967]: info: Adding inputs REST Endpoints Oct 10 11:00:51 motivo volumio[967]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 10 11:00:51 motivo volumio[967]: info: Scanning Audio Inputs Oct 10 11:00:51 motivo sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:51 motivo volumio[967]: info: Checking against Known Cards name Oct 10 11:00:51 motivo volumio[967]: info: Checking against Known Cards name Oct 10 11:00:51 motivo volumio[967]: info: Adding Server instance for streaming Oct 10 11:00:51 motivo volumio[967]: info: Refreshing TIDAL token Oct 10 11:00:51 motivo volumio[967]: info: Volumio Calling Home Oct 10 11:00:51 motivo volumio[967]: (node:967) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 10 11:00:51 motivo volumio[967]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 10 11:00:51 motivo volumio[967]: info: Serial port opened successfully Oct 10 11:00:51 motivo volumio[967]: info: Sending serial start messages Oct 10 11:00:51 motivo volumio[967]: info: Display with brightness control is connected, setting display brightness Oct 10 11:00:51 motivo volumio[967]: info: Setting Display Brightness to 12 Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 10 11:00:51 motivo volumio[967]: error: Cannot start Volumio Streaming Daemon Oct 10 11:00:51 motivo volumio[967]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 10 11:00:51 motivo volumio[967]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 10 11:00:51 motivo volumio[967]: info: RAAT Albumart path created successfully Oct 10 11:00:51 motivo volumio[967]: info: Bluetooth name changed to Motivo Oct 10 11:00:51 motivo volumio[967]: info: MPD Permissions set Oct 10 11:00:51 motivo volumio[967]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 10 11:00:51 motivo volumio[967]: info: MPD Permissions set Oct 10 11:00:51 motivo volumio[967]: info: Upmpdcli Daemon Started Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Oct 10 11:00:51 motivo volumio[967]: info: Setting Device Volume Override Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 10 11:00:51 motivo volumio[967]: info: Updating Volume Controller Parameters: Device: 1 Name: Analog + I2S + Headphones Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 10 11:00:51 motivo volumio[967]: info: Disabling external Volume Control Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:51 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 10 11:00:51 motivo volumio[967]: info: Setting Additional System Software info: Hardware Revision: 2.3 Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 10 11:00:51 motivo volumio[967]: info: Setting HW Firmware info: undefined Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 10 11:00:51 motivo volumio[967]: info: Setting HW Version info: 2.3 Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 10 11:00:51 motivo volumio[967]: info: Setting Additional System Software info: Hardware Revision: 2.3, Firmware Version: 0.1.2 Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 10 11:00:51 motivo volumio[967]: info: Setting HW Firmware info: 0.1.2 Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 10 11:00:51 motivo volumio[967]: info: Setting HW Version info: 2.3 Oct 10 11:00:51 motivo volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 10 11:00:51 motivo volumio[967]: info: MCU Signalled Headphone Mode Disabled Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: Updating RAAT Signal Path Oct 10 11:00:51 motivo volumio[967]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 10 11:00:51 motivo volumio[967]: info: MCU Signalled Sleep Mode Disabled Oct 10 11:00:51 motivo volumio[967]: info: Enabling Advanced system settings configuration Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Oct 10 11:00:51 motivo volumio[967]: info: Additional UI Settings Added for plugin music_service/inputs Oct 10 11:00:51 motivo volumio[967]: info: MCU Signalled Auto Boot Mode On Power Disabled Oct 10 11:00:51 motivo sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Oct 10 11:00:51 motivo sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:51 motivo sudo[1560]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:51 motivo volumio[967]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:51 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 10 11:00:52 motivo volumio[967]: info: Configured Audio card not found, not starting RAAT Oct 10 11:00:52 motivo volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 10 11:00:52 motivo volumio[967]: info: Volumio called home Oct 10 11:00:52 motivo sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 10 11:00:52 motivo sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo sudo[1576]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo volumio[967]: info: VolumeController:: Volume=undefined Mute =false Oct 10 11:00:52 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:52 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:52 motivo volumio[967]: info: Starting Shairport Sync Oct 10 11:00:52 motivo volumio[967]: info: Starting Shairport Sync Oct 10 11:00:52 motivo sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 11:00:52 motivo volumio-remote-updater[707]: [2024-10-10 11:00:52] [connect] Successful connection Oct 10 11:00:52 motivo sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo volumio[967]: info: Starting Shairport Sync Oct 10 11:00:52 motivo volumio[967]: info: Adding Inputs via Serial API Oct 10 11:00:52 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 11:00:52 motivo systemd[1]: shairport-sync.service: Succeeded. Oct 10 11:00:52 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:52 motivo sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 11:00:52 motivo sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo volumio[967]: info: Adding Advanced Audio Settings via Serial API Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 10 11:00:52 motivo volumio[967]: info: Additional UI Settings Added for plugin music_service/inputs Oct 10 11:00:52 motivo volumio[967]: info: Adding Advanced Audio Settings via Serial API Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 10 11:00:52 motivo volumio[967]: info: Preparing to generate the ALSA configuration file Oct 10 11:00:52 motivo sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 11:00:52 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:52 motivo sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo sudo[1579]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 11:00:52 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 10 11:00:52 motivo systemd[1]: shairport-sync.service: Succeeded. Oct 10 11:00:52 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:52 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:52 motivo sudo[1581]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo sudo[1584]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo volumio[967]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 10 11:00:52 motivo volumio[967]: info: Reading ALSA contributions from plugins. Oct 10 11:00:52 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:52 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:52 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:52 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::servicePushState Oct 10 11:00:52 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:52 motivo volumio[967]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:00:52 motivo volumio-remote-updater[707]: [2024-10-10 11:00:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728525652 101 Oct 10 11:00:52 motivo volumio[967]: 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 Oct 10 11:00:52 motivo volumio[967]: info: Shairport-Sync Started Oct 10 11:00:52 motivo volumio[967]: Error adding Membership: Error: addMembership EINVAL Oct 10 11:00:52 motivo volumio[967]: info: Shairport-Sync Started Oct 10 11:00:52 motivo volumio[967]: info: Shairport-Sync Started Oct 10 11:00:52 motivo volumio[967]: info: Asound.conf file written Oct 10 11:00:52 motivo sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 10 11:00:52 motivo sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo sudo[1592]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo volumio[967]: info: Output device has changed, restarting MPD Oct 10 11:00:52 motivo sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 11:00:52 motivo sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo mpd[1507]: Oct 10 11:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 11:00:52 motivo volumio[967]: info: Output device has changed, restarting Shairport Sync Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:52 motivo sudo[1597]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 11:00:52 motivo sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 11:00:52 motivo volumio[967]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 10 11:00:52 motivo systemd[1]: mpd.service: Succeeded. Oct 10 11:00:52 motivo systemd[1]: Stopped Music Player Daemon. Oct 10 11:00:52 motivo volumio[967]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: Preparing to generate the ALSA configuration file Oct 10 11:00:52 motivo systemd[1]: Starting Music Player Daemon... Oct 10 11:00:52 motivo volumio[967]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 10 11:00:52 motivo volumio[967]: info: Reading ALSA contributions from plugins. Oct 10 11:00:52 motivo volumio[967]: info: MPD Permissions set Oct 10 11:00:52 motivo volumio[967]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:52 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:52 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:52 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:52 motivo volumio[967]: info: Starting Shairport Sync Oct 10 11:00:52 motivo sudo[1606]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 11:00:52 motivo sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo volumio[967]: info: Asound.conf file unchanged, so no further update is needed Oct 10 11:00:52 motivo volumio[967]: info: Output device has changed, restarting MPD Oct 10 11:00:52 motivo sudo[1606]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 11:00:52 motivo sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 11:00:52 motivo sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:52 motivo sudo[1617]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 11:00:52 motivo systemd[1]: shairport-sync.service: Succeeded. Oct 10 11:00:52 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:52 motivo volumio[967]: info: Output device has changed, restarting Shairport Sync Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:52 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:52 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:52 motivo sudo[1614]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:52 motivo sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 11:00:52 motivo sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:53 motivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 10 11:00:53 motivo systemd[1]: mpd.service: Succeeded. Oct 10 11:00:53 motivo systemd[1]: Stopped Music Player Daemon. Oct 10 11:00:53 motivo volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 11:00:53 motivo volumio[967]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo systemd[1]: Starting Music Player Daemon... Oct 10 11:00:53 motivo volumio[967]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: Discovery: adding 215279bf-9a83-4649-a50f-18569b88027f Oct 10 11:00:53 motivo volumio[967]: info: Discovery: Found device Motivo Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:53 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:53 motivo volumio[967]: info: MRS: Pushing multiroomSync output for this device Oct 10 11:00:53 motivo volumio[967]: info: MRS: Pushing multiroomSync output Oct 10 11:00:53 motivo volumio[967]: info: Adding audio output: Oct 10 11:00:53 motivo volumio[967]: info: Adding audio output: Oct 10 11:00:53 motivo volumio[967]: info: Discovery: this is already registered, 215279bf-9a83-4649-a50f-18569b88027f Oct 10 11:00:53 motivo volumio[967]: info: Discovery: Found device Motivo Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:53 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:53 motivo volumio[967]: info: MPD Permissions set Oct 10 11:00:53 motivo volumio[967]: info: Shairport-Sync Started Oct 10 11:00:53 motivo volumio[967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 10 11:00:53 motivo sudo[1630]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 11:00:53 motivo sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo sudo[1630]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:53 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:53 motivo volumio[967]: info: MRS: Pushing multiroomSync output update for this device Oct 10 11:00:53 motivo volumio[967]: info: MRS: Pushing multiroomSync output Oct 10 11:00:53 motivo volumio[967]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: Not Reporting Auto name since its the default one Oct 10 11:00:53 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:53 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:53 motivo volumio[967]: info: MRS: Pushing multiroomSync output update for this device Oct 10 11:00:53 motivo volumio[967]: info: MRS: Pushing multiroomSync output Oct 10 11:00:53 motivo volumio[967]: info: Starting Shairport Sync Oct 10 11:00:53 motivo volumio[967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 10 11:00:53 motivo sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:53 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:53 motivo sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 10 11:00:53 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 11:00:53 motivo systemd[1]: shairport-sync.service: Succeeded. Oct 10 11:00:53 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:53 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 11:00:53 motivo sudo[1645]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:53 motivo volumio[967]: info: Configured Audio card not found, not starting RAAT Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:00:53 motivo volumio[967]: info: Not Reporting Auto name since its the default one Oct 10 11:00:53 motivo volumio[967]: info: Access Token successfully retrieved Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 11:00:53 motivo volumio[967]: info: [1728525653671] CoreMusicLibrary::Adding element QOBUZ Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:00:53 motivo volumio[967]: Cannot find translation for source QOBUZ Oct 10 11:00:53 motivo volumio[967]: info: Stopping AccessToken refresher cron for QOBUZ Oct 10 11:00:53 motivo volumio[967]: info: AccessToken refresher cron started for QOBUZ Oct 10 11:00:53 motivo volumio[967]: info: Adding QOBUZ REST API Endpoints Oct 10 11:00:53 motivo volumio[967]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 10 11:00:53 motivo volumio[967]: info: Shairport-Sync Started Oct 10 11:00:53 motivo sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 10 11:00:53 motivo sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:53 motivo systemd[1]: Started Volumio Bluetooth Module. Oct 10 11:00:53 motivo sudo[1657]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:53 motivo sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 10 11:00:53 motivo sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:53 motivo volumiobt[1659]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 10 11:00:53 motivo sudo[1660]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:53 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:53 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 10 11:00:54 motivo volumio[967]: No protocol specified Oct 10 11:00:54 motivo volumio[967]: xcb_connection_has_error() returned true Oct 10 11:00:54 motivo volumio[967]: info: Configured Audio card not found, not starting RAAT Oct 10 11:00:54 motivo volumio[967]: info: Volumio BT Module successfully started Oct 10 11:00:54 motivo volumio[967]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 4 Oct 10 11:00:54 motivo volumio[967]: info: TidalConnect service stoped! Oct 10 11:00:54 motivo mpd[1634]: Oct 10 11:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 11:00:54 motivo volumio[967]: info: Access Token successfully retrieved Oct 10 11:00:54 motivo systemd[1]: Started Music Player Daemon. Oct 10 11:00:54 motivo sudo[1492]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:54 motivo sudo[1622]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:54 motivo sudo[1600]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:54 motivo sudo[1479]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:54 motivo volumio[967]: Upnp client error: Error: This socket has been ended by the other party Oct 10 11:00:54 motivo volumio[967]: info: Initializing I2S Bus Oct 10 11:00:54 motivo volumio[967]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 10 11:00:54 motivo volumio[967]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 10 11:00:54 motivo sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 10 11:00:54 motivo sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:54 motivo systemd[1]: Started Volumio Tidal Connect Service. Oct 10 11:00:54 motivo volumio[967]: error: MPD error: The expression evaluated to a falsy value: Oct 10 11:00:54 motivo volumio[967]: assert.ok(self.idling) Oct 10 11:00:54 motivo volumio[967]: error: The expression evaluated to a falsy value: Oct 10 11:00:54 motivo volumio[967]: assert.ok(self.idling) Oct 10 11:00:54 motivo volumio[967]: error: MPD error: The expression evaluated to a falsy value: Oct 10 11:00:54 motivo volumio[967]: assert.ok(self.idling) Oct 10 11:00:54 motivo volumio[967]: error: The expression evaluated to a falsy value: Oct 10 11:00:54 motivo volumio[967]: assert.ok(self.idling) Oct 10 11:00:54 motivo volumio[967]: error: MPD error: The expression evaluated to a falsy value: Oct 10 11:00:54 motivo volumio[967]: assert.ok(self.idling) Oct 10 11:00:54 motivo volumio[967]: error: The expression evaluated to a falsy value: Oct 10 11:00:54 motivo volumio[967]: assert.ok(self.idling) Oct 10 11:00:54 motivo volumio[967]: info: MPD running with PID1634 Oct 10 11:00:54 motivo volumio[967]: ,establishing connection Oct 10 11:00:54 motivo sudo[1678]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:54 motivo volumio[967]: error: updateQueue error: null Oct 10 11:00:54 motivo volumio[967]: error: updateQueue error: null Oct 10 11:00:54 motivo volumio[967]: info: Successfully retrieved User Session From TIDAL Oct 10 11:00:54 motivo volumio[1475]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 10 11:00:54 motivo volumio[967]: info: Executing endpoint tc_getconfig Oct 10 11:00:54 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 10 11:00:54 motivo vtcs[1680]: STARTING TidalConnect services, version: 1.4.0.34 Oct 10 11:00:54 motivo volumio[967]: info: Successfully retrieved User Subscription From TIDAL Oct 10 11:00:54 motivo volumio[967]: info: Adding TIDAL to Browse Sources Oct 10 11:00:54 motivo volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 11:00:54 motivo volumio[967]: info: [1728525654739] CoreMusicLibrary::Adding element TIDAL Oct 10 11:00:54 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:00:54 motivo volumio[967]: Cannot find translation for source QOBUZ Oct 10 11:00:54 motivo volumio[967]: Cannot find translation for source TIDAL Oct 10 11:00:54 motivo volumio[967]: info: Stopping AccessToken refresher cron Oct 10 11:00:54 motivo volumio[967]: info: AccessToken refresher cron started Oct 10 11:00:54 motivo volumio[967]: info: Adding TIDAL REST API Endpoints Oct 10 11:00:54 motivo volumio[967]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 10 11:00:54 motivo vtcs[1680]: STARTED TidalConnect services. Oct 10 11:00:54 motivo volumio[967]: info: Executing endpoint tc_connect Oct 10 11:00:54 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 10 11:00:54 motivo volumio[967]: info: Connecting to TidalConnect Oct 10 11:00:54 motivo volumio[967]: info: CoreCommandRouter::servicePushState Oct 10 11:00:54 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:54 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:54 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:54 motivo volumio[967]: info: MRS: Pushing multiroomSync output update for this device Oct 10 11:00:54 motivo volumio[967]: info: MRS: Pushing multiroomSync output Oct 10 11:00:54 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:54 motivo volumio[967]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 10 11:00:54 motivo volumio[967]: info: CoreCommandRouter::servicePushState Oct 10 11:00:54 motivo volumio[967]: info: CoreStateMachine::pushState Oct 10 11:00:54 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:54 motivo volumio[967]: info: CoreCommandRouter::volumioPushState Oct 10 11:00:54 motivo volumio[967]: info: MRS: Pushing multiroomSync output update for this device Oct 10 11:00:54 motivo volumio[967]: info: MRS: Pushing multiroomSync output Oct 10 11:00:54 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:54 motivo volumio[967]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 10 11:00:54 motivo sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 10 11:00:54 motivo sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:54 motivo volumiobt[1659]: pulseaudio: no process found Oct 10 11:00:54 motivo sudo[1717]: pam_unix(sudo:session): session closed for user root Oct 10 11:00:55 motivo sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.133/contents /mnt/NAS/Soundgenic1 Oct 10 11:00:55 motivo sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:00:55 motivo dbus-daemon[716]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.25' (uid=1000 pid=1724 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 10 11:00:55 motivo kernel: CIFS: Attempting to mount //192.168.1.133/contents Oct 10 11:00:55 motivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 10 11:00:55 motivo volumio[967]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 10 11:00:55 motivo dbus-daemon[716]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 10 11:00:55 motivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 10 11:00:55 motivo rtkit-daemon[1727]: Successfully called chroot. Oct 10 11:00:55 motivo rtkit-daemon[1727]: Successfully dropped privileges. Oct 10 11:00:55 motivo rtkit-daemon[1727]: Successfully limited resources. Oct 10 11:00:55 motivo rtkit-daemon[1727]: Running. Oct 10 11:00:55 motivo rtkit-daemon[1727]: Canary thread running. Oct 10 11:00:55 motivo rtkit-daemon[1727]: Watchdog thread running. Oct 10 11:00:55 motivo dbus-daemon[716]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.26' (uid=0 pid=1727 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 10 11:00:55 motivo systemd[1]: Starting Authorization Manager... Oct 10 11:00:55 motivo volumio[967]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 10 11:00:55 motivo volumio[967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 10 11:00:55 motivo polkitd[1730]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 10 11:00:55 motivo dbus-daemon[716]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 10 11:00:55 motivo systemd[1]: Started Authorization Manager. Oct 10 11:00:55 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:55 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:55 motivo volumio[967]: info: MCU Signalled Playback Inactive Oct 10 11:00:55 motivo pulseaudio[1724]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 10 11:00:55 motivo pulseaudio[1724]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 10 11:00:55 motivo bluetoothd[778]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Oct 10 11:00:55 motivo bluetoothd[778]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Oct 10 11:00:55 motivo pulseaudio[1724]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Oct 10 11:00:55 motivo volumiobt[1659]: Applying permissions Oct 10 11:00:55 motivo volumiobt[1659]: Setting BT discoverable and pairable Oct 10 11:00:55 motivo kernel: Bluetooth: RFCOMM TTY layer initialized Oct 10 11:00:55 motivo kernel: Bluetooth: RFCOMM socket layer initialized Oct 10 11:00:55 motivo kernel: Bluetooth: RFCOMM ver 1.11 Oct 10 11:00:55 motivo volumiobt[1659]: Agent registered Oct 10 11:00:55 motivo volumiobt[1659]: [bluetooth]# -e power on Oct 10 11:00:55 motivo volumiobt[1659]: Invalid command in menu main: -e Oct 10 11:00:55 motivo volumiobt[1659]: Oct 10 11:00:55 motivo volumiobt[1659]: Use "help" for a list of available commands in a menu. Oct 10 11:00:55 motivo volumiobt[1659]: Use "menu " if you want to enter any submenu. Oct 10 11:00:55 motivo volumiobt[1659]: Use "back" if you want to return to menu main. Oct 10 11:00:55 motivo volumiobt[1659]: [bluetooth]# agent on Oct 10 11:00:55 motivo volumiobt[1659]: Agent is already registered Oct 10 11:00:55 motivo volumiobt[1659]: [bluetooth]# discoverable on Oct 10 11:00:55 motivo volumiobt[1659]: [bluetooth]# pairable on Oct 10 11:00:55 motivo volumiobt[1659]: [bluetooth]# agent NoInputNoOutput Oct 10 11:00:55 motivo volumiobt[1659]: Agent is already registered Oct 10 11:00:55 motivo volumiobt[1659]: [bluetooth]# default-agent Oct 10 11:00:55 motivo volumiobt[1659]: [bluetooth]# quit Oct 10 11:00:55 motivo volumiobt[1659]: [59B blob data] Oct 10 11:00:55 motivo volumio[967]: info: Successfully initialized Motivo I2S Bus Oct 10 11:00:56 motivo volumio[967]: info: MRS: Getting audio outputs on start Oct 10 11:00:56 motivo volumio[967]: info: MRS: Requesting all other devices output Oct 10 11:00:56 motivo volumio[967]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 5 Oct 10 11:00:56 motivo systemd[1]: systemd-hostnamed.service: Succeeded. Oct 10 11:00:56 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:57 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 11:00:57 motivo volumio[967]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 10 11:00:57 motivo volumio[967]: info: Received Get System Info Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 11:00:57 motivo volumio[967]: info: Discovery: Getting this device information Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:57 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:57 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:57 motivo volumio[967]: info: Listing playlists Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 11:00:57 motivo volumio[967]: info: TidalConnect service started! Oct 10 11:00:57 motivo volumio[967]: info: Completed starting Core Plugins Oct 10 11:00:57 motivo volumio[967]: info: ------------------------------------------- Oct 10 11:00:57 motivo volumio[967]: info: ----- MyVolumio plugins startup ---- Oct 10 11:00:57 motivo volumio[967]: info: ------------------------------------------- Oct 10 11:00:57 motivo volumio[967]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 10 11:00:57 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:57 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:58 motivo volumio[1475]: ....................................++++ Oct 10 11:00:58 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 11:00:58 motivo volumio[967]: info: Received Get System Info Oct 10 11:00:58 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 11:00:58 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 11:00:58 motivo volumio[967]: info: Discovery: Getting this device information Oct 10 11:00:58 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:58 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:58 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 11:00:58 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 10 11:00:59 motivo volumio[1475]: ...........++++ Oct 10 11:00:59 motivo volumio[1475]: e is 65537 (0x010001) Oct 10 11:00:59 motivo volumio[1475]: writing RSA key Oct 10 11:00:59 motivo volumio[967]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 11:00:59 motivo volumio[967]: info: Received Get System Info Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 11:00:59 motivo volumio[967]: info: Discovery: Getting this device information Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:00:59 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 11:00:59 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:00:59 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:00:59 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:00:59 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:01:00 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 10 11:01:01 motivo volumio[967]: info: Checking for updated MCU Firmware Oct 10 11:01:01 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 11:01:01 motivo volumio[967]: info: Firware on device is on latest version, no need to update Oct 10 11:01:05 motivo sudo[1721]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:05 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 10 11:01:05 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 10 11:01:05 motivo volumio[967]: info: Cannot mount NAS Soundgenic1 at system boot, trial number 2 ,retrying in 5 seconds Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 10 11:01:05 motivo volumio[967]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 10 11:01:06 motivo volumio[967]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 10 11:01:06 motivo volumio[967]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 10 11:01:06 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:06 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:06 motivo volumio[967]: info: Starting MyVolumio Remote Streaming Endpoints Oct 10 11:01:06 motivo volumio[967]: info: MyVolumio login type: Token Oct 10 11:01:06 motivo volumio[967]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 10 11:01:06 motivo volumio[967]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Oct 10 11:01:06 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 10 11:01:06 motivo volumio[967]: error: MyVolumio Custom Token format not valid, refreshing it Oct 10 11:01:07 motivo sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 11:01:07 motivo sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:01:07 motivo sudo[1774]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:07 motivo sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 11:01:07 motivo sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:01:07 motivo sudo[1777]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:07 motivo volumio[967]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.110 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 10 11:01:07 motivo sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 11:01:07 motivo sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:01:07 motivo sudo[1780]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:07 motivo sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 11:01:07 motivo sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:01:07 motivo volumio[967]: info: MyVolumio login type: Token Oct 10 11:01:07 motivo sudo[1783]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:07 motivo volumio[967]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.110 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 10 11:01:07 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:08 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 11:01:08 motivo volumio[967]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 10 11:01:08 motivo volumio[967]: info: Received Get System Info Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 11:01:08 motivo volumio[967]: info: Discovery: Getting this device information Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:08 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:08 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:08 motivo volumio[967]: info: Listing playlists Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 10 11:01:08 motivo volumio[967]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 10 11:01:08 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:08 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:08 motivo volumio[967]: info: MyVolumio token set successfully Oct 10 11:01:08 motivo volumio[967]: info: MYVOLUMIO: Adding device Oct 10 11:01:08 motivo volumio[967]: info: MYVOLUMIO: Evaluating Server Oct 10 11:01:09 motivo volumio[967]: info: MyVolumio status changed Oct 10 11:01:09 motivo volumio[967]: info: Streaming services startup Oct 10 11:01:09 motivo volumio[967]: info: Starting Streaming Daemon Oct 10 11:01:09 motivo volumio[967]: info: Removing browser output: myVolumio user plan is not superstar Oct 10 11:01:09 motivo volumio[967]: info: Removing audio output: Oct 10 11:01:09 motivo volumio[967]: info: Stoppping Tunnel 1 Oct 10 11:01:09 motivo sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 10 11:01:09 motivo sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:01:09 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 10 11:01:09 motivo sudo[1803]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:09 motivo sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 10 11:01:09 motivo sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:01:09 motivo volumio[967]: error: Cannot start Volumio Streaming Daemon Oct 10 11:01:09 motivo volumio[967]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 10 11:01:09 motivo volumio[967]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 10 11:01:09 motivo sudo[1806]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:09 motivo volumio[967]: info: Remote SSH Stopped Oct 10 11:01:09 motivo volumio[967]: info: Setting Geolocation for MyVolumio to as1 Oct 10 11:01:09 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:09 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 11:01:09 motivo volumio[967]: info: Received Get System Info Oct 10 11:01:09 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 11:01:09 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 11:01:09 motivo volumio[967]: info: Discovery: Getting this device information Oct 10 11:01:09 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:09 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:09 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 11:01:09 motivo volumio[967]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 10 11:01:10 motivo sudo[1809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.133/contents /mnt/NAS/Soundgenic1 Oct 10 11:01:10 motivo sudo[1809]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:01:10 motivo kernel: CIFS: Attempting to mount //192.168.1.133/contents Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 10 11:01:10 motivo volumio[967]: info: Updating MyVolumio device info Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 10 11:01:10 motivo volumio[967]: info: Received Get System Version Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 11:01:10 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:01:10 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:01:10 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:01:10 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:01:11 motivo volumio[967]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 10 11:01:11 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 11:01:11 motivo volumio[967]: info: Received Get System Info Oct 10 11:01:11 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 11:01:11 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 11:01:11 motivo volumio[967]: info: Discovery: Getting this device information Oct 10 11:01:11 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:11 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:11 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 11:01:13 motivo volumio[967]: info: MYVOLUMIO: Adding device Oct 10 11:01:13 motivo volumio[967]: info: MYVOLUMIO: Evaluating Server Oct 10 11:01:14 motivo volumio[967]: info: Setting Geolocation for MyVolumio to as1 Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 11:01:14 motivo volumio[967]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 10 11:01:14 motivo volumio[967]: info: Received Get System Version Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 11:01:14 motivo volumio[967]: info: Received Get System Info Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 11:01:14 motivo volumio[967]: info: Discovery: Getting this device information Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:14 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 11:01:14 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:14 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:15 motivo volumio[967]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 10 11:01:15 motivo volumio[967]: info: Updating MyVolumio device info Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 11:01:15 motivo volumio[967]: info: Retrieving Cloud Streaming UI Oct 10 11:01:15 motivo volumio[967]: info: Getting Tidal Cloud Configuration Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 11:01:15 motivo volumio[967]: info: Getting Qobuz Cloud Configuration Oct 10 11:01:15 motivo volumio[967]: info: Asking plugin for UI Config Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 11:01:15 motivo volumio[967]: info: Getting Spotify Cloud Configuration Oct 10 11:01:15 motivo volumio[967]: info: Asking plugin for UI Config Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 11:01:15 motivo volumio[967]: info: Saving Spotify Acccount Oct 10 11:01:15 motivo volumio[967]: info: Got it Oct 10 11:01:15 motivo volumio[967]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 10 11:01:15 motivo volumio[967]: info: Got Tidal Cloud Configuration Oct 10 11:01:15 motivo volumio[967]: info: Got it Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 11:01:15 motivo volumio[967]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 10 11:01:15 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 10 11:01:16 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:01:16 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:01:16 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:01:16 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:01:16 motivo volumio[967]: info: Executing endpoint metavolumio Oct 10 11:01:16 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 10 11:01:17 motivo volumio[967]: error: MyVolumio Plugin failed to start in a timely fashion Oct 10 11:01:17 motivo volumio[967]: info: BOOT COMPLETED Oct 10 11:01:17 motivo volumio[967]: [Metrics] CommandRouter: 43s 754.10ms Oct 10 11:01:17 motivo volumio[967]: info: CoreCommandRouter::volumiosetStartupVolume Oct 10 11:01:17 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 11:01:17 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:17 motivo volumio[967]: info: CoreCommandRouter::Close All Modals sent Oct 10 11:01:17 motivo volumio[967]: info: CoreCommandRouter::Close All Modals sent Oct 10 11:01:17 motivo volumio[967]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 10 11:01:17 motivo volumio[967]: No protocol specified Oct 10 11:01:17 motivo volumio[967]: xcb_connection_has_error() returned true Oct 10 11:01:17 motivo volumio[967]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Oct 10 11:01:17 motivo volumio[967]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 10 11:01:17 motivo volumio[967]: aplay: main:828: audio open error: No such device Oct 10 11:01:17 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 10 11:01:18 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 10 11:01:18 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 11:01:18 motivo volumio[967]: No protocol specified Oct 10 11:01:18 motivo volumio[967]: xcb_connection_has_error() returned true Oct 10 11:01:18 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 10 11:01:18 motivo volumio[967]: info: Stopping RAAT Plugin Oct 10 11:01:18 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 10 11:01:18 motivo sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Oct 10 11:01:18 motivo sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 11:01:18 motivo sudo[1856]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:18 motivo volumio[967]: info: Raat Daemon stopped successfully Oct 10 11:01:19 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 11:01:20 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 10 11:01:20 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 10 11:01:20 motivo sudo[1809]: pam_unix(sudo:session): session closed for user root Oct 10 11:01:20 motivo volumio[967]: info: Cannot mount NAS Soundgenic1 at system boot, trial number 3 ,retrying in 5 seconds Oct 10 11:01:21 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 11:01:21 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 11:01:21 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 11:01:21 motivo volumio-remote-updater[707]: No test mode Oct 10 11:01:21 motivo volumio-remote-updater[707]: No alpha test mode Oct 10 11:01:21 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 10 11:01:21 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 11:01:21 motivo volumio[967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 10 11:01:21 motivo volumio[967]: info: CoreCommandRouter::volumioGetState Oct 10 11:01:21 motivo volumio[967]: info: CorePlayQueue::getTrack 0 Oct 10 11:01:21 motivo volumio[967]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 10 11:01:21 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 10 11:01:23 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 11:01:24 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 10 11:01:25 motivo volumio[967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 10 11:01:25 motivo volumio[967]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 11:01:25 motivo volumio[967]: TypeError: Cannot read property 'replace' of undefined Oct 10 11:01:25 motivo volumio[967]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Oct 10 11:01:25 motivo volumio[967]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Oct 10 11:01:25 motivo volumio[967]: at listOnTimeout (internal/timers.js:554:17) Oct 10 11:01:25 motivo volumio[967]: at processTimers (internal/timers.js:497:7) Oct 10 11:01:25 motivo volumio[967]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 11:01:26 motivo sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 11:00 Oct 10 11:01:26 motivo sudo[1919]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 07:14:04 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="1b70fb201c03de663c0d0492dcaf203c"