-- Logs begin at Sat 2025-02-15 19:22:51 UTC, end at Sat 2025-02-15 20:06:24 UTC. -- Feb 15 20:05:00 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:00 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:00 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:00 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:01 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:01 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:01 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:01 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:02 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:02 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:02 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:02 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:03 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:03 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:03 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:03 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:04 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:04 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:04 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:04 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:05 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:05 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:05 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:05 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:06 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:06 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:06 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:06 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:07 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:07 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:07 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:07 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:08 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:08 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:08 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:08 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:09 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:09 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:09 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:09 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:10 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:10 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:10 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:10 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:11 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:11 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:11 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:11 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:12 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:12 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:12 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:12 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:13 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:13 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:13 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:13 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:14 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:14 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:14 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:14 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:15 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:15 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:15 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:15 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:16 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:16 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:16 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:16 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:17 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:17 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:17 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:17 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:18 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:18 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:18 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:18 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:19 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:19 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:19 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:19 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:20 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:20 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:20 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:20 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:21 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:21 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:21 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:21 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:22 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:22 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:22 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:22 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:23 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:23 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:23 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:23 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:24 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:24 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:24 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:24 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:25 volumio-primo volumio[1100]: info: CoreCommandRouter::Close All Modals sent Feb 15 20:05:25 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:25 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:25 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:25 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:26 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:26 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:26 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:26 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:27 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:27 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:27 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:27 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:28 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 15 20:05:28 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 15 20:05:28 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:28 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:28 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:28 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:29 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:29 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:29 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:29 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:30 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:30 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:30 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:30 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:31 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:31 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:31 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:31 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:32 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:32 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:32 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:32 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:33 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:33 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:33 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:33 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:34 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:34 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:34 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:34 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:35 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:35 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:35 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:35 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 15 20:05:36 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:36 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:36 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:36 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 15 20:05:37 volumio-primo volumio[1100]: info: Received Get System Version Feb 15 20:05:37 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:37 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Feb 15 20:05:37 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:37 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:37 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:37 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:38 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:38 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:38 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:38 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:39 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:39 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:39 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:39 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:40 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:40 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:40 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:40 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: Retrieving Cloud Streaming UI Feb 15 20:05:41 volumio-primo volumio[1100]: info: Getting Tidal Cloud Configuration Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: Getting Qobuz Cloud Configuration Feb 15 20:05:41 volumio-primo volumio[1100]: info: Asking plugin for UI Config Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: Getting Spotify Cloud Configuration Feb 15 20:05:41 volumio-primo volumio[1100]: info: Asking plugin for UI Config Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: Saving Spotify Acccount Feb 15 20:05:41 volumio-primo volumio[1100]: info: Got it Feb 15 20:05:41 volumio-primo volumio[1100]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 15 20:05:41 volumio-primo volumio[1100]: info: Got Tidal Cloud Configuration Feb 15 20:05:41 volumio-primo volumio[1100]: info: Got it Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioGetBrowseSources Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioGetBrowseSources Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioGetBrowseSources Feb 15 20:05:41 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:41 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:41 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:41 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:42 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:05:42 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 15 20:05:42 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:42 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:42 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:42 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:43 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:43 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:43 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:43 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand update Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:05:44 volumio-primo volumio[1100]: info: Feb 15 20:05:44 volumio-primo volumio[1100]: ---------------------------- MPD announces state update: update Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand update took 4 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: info: ControllerMpd::getState Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand status took 4 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseState Feb 15 20:05:44 volumio-primo volumio[1100]: info: Command Router : Notfying DB Updatetrue Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseState Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:05:44 volumio-primo volumio[1100]: info: Feb 15 20:05:44 volumio-primo volumio[1100]: ---------------------------- MPD announces state update: update Feb 15 20:05:44 volumio-primo volumio[1100]: info: ControllerMpd::getState Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: ControllerMpd::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::servicePushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2139606,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: CURRENT POSITION 0 Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::syncState stateService play Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:05:44 volumio-primo volumio[1100]: info: Received an update from plugin. extracting info from payload Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: ------------------------------ 55ms Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand status took 33 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand status took 31 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseState Feb 15 20:05:44 volumio-primo volumio[1100]: info: Command Router : Notfying DB Updatefalse Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::Close All Modals sent Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseState Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: ControllerMpd::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::servicePushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2139606,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: CURRENT POSITION 0 Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::syncState stateService play Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:05:44 volumio-primo volumio[1100]: info: Received an update from plugin. extracting info from payload Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: ------------------------------ 122ms Feb 15 20:05:44 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:44 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:44 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:44 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:45 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:45 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:45 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:45 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:46 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:05:46 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:46 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:46 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:46 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:47 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:47 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:47 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:47 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:48 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:48 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:48 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:48 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:49 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:49 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:49 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:49 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:50 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 15 20:05:50 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 15 20:05:50 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:05:50 volumio-primo volumio[1100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:05:50 volumio-primo volumio[1100]: Error: getaddrinfo -3007 Feb 15 20:05:50 volumio-primo volumio[1100]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Feb 15 20:05:50 volumio-primo volumio[1100]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Feb 15 20:05:50 volumio-primo volumio[1100]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Feb 15 20:05:50 volumio-primo volumio[1100]: code: -3007, Feb 15 20:05:50 volumio-primo volumio[1100]: errno: -3007, Feb 15 20:05:50 volumio-primo volumio[1100]: syscall: 'getaddrinfo' Feb 15 20:05:50 volumio-primo volumio[1100]: } Feb 15 20:05:50 volumio-primo volumio[1100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:05:50 volumio-primo sudo[10681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 20:04 Feb 15 20:05:50 volumio-primo sudo[10681]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:50 volumio-primo sudo[10681]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:50 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:50 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:50 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:50 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:51 volumio-primo vtcs[2217]: [2025-02-15 20:05:51.482] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Feb 15 20:05:51 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:51] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Feb 15 20:05:51 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:51] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Feb 15 20:05:51 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:51] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Feb 15 20:05:51 volumio-primo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 15 20:05:51 volumio-primo systemd[1]: upmpdcli.service: Succeeded. Feb 15 20:05:51 volumio-primo systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 15 20:05:51 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:05:51 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:05:51 volumio-primo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 15 20:05:51 volumio-primo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 15 20:05:51 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:05:51 volumio-primo systemd[1]: Stopped Volumio Backend Module. Feb 15 20:05:51 volumio-primo systemd[1]: Started Volumio Backend Module. Feb 15 20:05:51 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:05:51 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:51 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:51 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:51 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:52 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:52 volumio-primo volumio[10703]: info: ----- Volumio3 ---- Feb 15 20:05:52 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:52 volumio-primo volumio[10703]: info: ----- System startup ---- Feb 15 20:05:52 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:52 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:52 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:52 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:52 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:53 volumio-primo volumio[10703]: info: MYVOLUMIO Environment detected Feb 15 20:05:53 volumio-primo volumio[10703]: info: Plugin folders cleanup Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning into folder /volumio/app/plugins/ Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category audio_interface Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category miscellanea Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category music_service Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category plugins.json Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category system_controller Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category user_interface Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning into folder /data/plugins/ Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category RoonBridge Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category audio_interface Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category music_service Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category system_controller Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category system_hardware Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category user_interface Feb 15 20:05:53 volumio-primo volumio[10703]: info: Plugin folders cleanup completed Feb 15 20:05:53 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:53 volumio-primo volumio[10703]: info: ----- Core plugins startup ---- Feb 15 20:05:53 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugins from folder /volumio/app/plugins/ Feb 15 20:05:53 volumio-primo volumio[10703]: info: Adding plugin upnp to MyMusic Plugins Feb 15 20:05:53 volumio-primo volumio[10703]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 15 20:05:53 volumio-primo volumio[10703]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugins from folder /data/plugins/ Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugin "system"... Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugin "ampswitch"... Feb 15 20:05:53 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin ampswitch Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugin "appearance"... Feb 15 20:05:53 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:53 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:53 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:53 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugin "network"... Feb 15 20:05:53 volumio-primo volumio[10703]: info: Refreshing Cached IP Addresses Feb 15 20:05:54 volumio-primo sudo[10730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:05:54 volumio-primo sudo[10732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "services"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "alsa_controller"... Feb 15 20:05:54 volumio-primo sudo[10730]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:54 volumio-primo sudo[10732]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:54 volumio-primo sudo[10732]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:54 volumio-primo sudo[10730]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:54 volumio-primo sudo[10744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 15 20:05:54 volumio-primo sudo[10744]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:54 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "wizard"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "networkfs"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Starting Udev Watcher for removable devices Feb 15 20:05:54 volumio-primo volumio[10703]: info: Ignoring mount for partition: BOOT Feb 15 20:05:54 volumio-primo volumio[10703]: info: Ignoring mount for partition: volumio Feb 15 20:05:54 volumio-primo volumio[10703]: info: Ignoring mount for partition: volumio_data Feb 15 20:05:54 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "volumio_command_line_client"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "upnp"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: [1739649954187] Starting Upmpd Daemon Feb 15 20:05:54 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "my_music"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "mpd"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "upnp_browser"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "alarm-clock"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "airplay_emulation"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "last_100"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "webradio"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "i2s_dacs"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: I2S DAC not set, start Auto-detection Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "volumiodiscovery"... Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** For more information see Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** For more information see Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** For more information see Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** For more information see Feb 15 20:05:54 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 15 20:05:54 volumio-primo volumio[10703]: info: Discovery: Started advertising with name: Volumio primo Feb 15 20:05:54 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "outputs"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "albumart"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Plugin example_plugin is not enabled Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "inputs"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "updater_comm"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Plugin mpdemulation is not enabled Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "rest_api"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "websocket"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Starting Socket.io Server version 2.3.0 Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "fusiondsp"... Feb 15 20:05:54 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:54 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:54 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:54 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:55 volumio-primo volumio[10703]: Forking 3 albumart workers Feb 15 20:05:55 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin fusiondsp Feb 15 20:05:55 volumio-primo volumio[10703]: info: Loading plugin "pandora"... Feb 15 20:05:55 volumio-primo sudo[10744]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:55 volumio-primo volumio[10703]: Starting albumart workers Feb 15 20:05:55 volumio-primo volumio[10703]: Starting albumart workers Feb 15 20:05:55 volumio-primo volumio[10703]: Starting albumart workers Feb 15 20:05:55 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin pandora Feb 15 20:05:55 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:55 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:55 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:55 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:55 volumio-primo volumio[10703]: info: Loading plugin "ir_controller"... Feb 15 20:05:55 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin ir_controller Feb 15 20:05:56 volumio-primo volumio[10703]: info: Loading plugin "rotaryencoder2"... Feb 15 20:05:56 volumio-primo volumiologrotate[541]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 15 20:05:56 volumio-primo volumiologrotate[541]: ls: cannot access 'PRIMO': No such file or directory Feb 15 20:05:56 volumio-primo volumio[10703]: info: Loading plugin "Systeminfo"... Feb 15 20:05:56 volumio-primo volumio[10703]: info: Loading i18n strings for locale it Feb 15 20:05:56 volumio-primo volumio[10703]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:05:56 volumio-primo volumio[10703]: Updating browse sources language Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:56 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:56] [connect] Successful connection Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::initPlayerControls Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: Express server listening on port 3000 Feb 15 20:05:56 volumio-primo volumio[10703]: [Metrics] WebUI: 4s 25.87ms Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::resetVolumioState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::getcurrentVolume Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 15 20:05:56 volumio-primo volumio[10703]: info: Completed loading Core Plugins Feb 15 20:05:56 volumio-primo volumio[10703]: info: Preparing to generate the ALSA configuration file Feb 15 20:05:56 volumio-primo volumio[10703]: info: Volumio Network Manager: Network status updated: 3 Feb 15 20:05:56 volumio-primo volumio[10703]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:05:56 volumio-primo volumio[10703]: info: Reading ALSA contributions from plugins. Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Feb 15 20:05:56 volumio-primo volumio[10703]: 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 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Feb 15 20:05:56 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739649956 101 Feb 15 20:05:56 volumio-primo volumio[10703]: 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: 9 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 15 20:05:56 volumio-primo volumio[10703]: info: Reloading queue from file Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::getQueue Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getQueue Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetVisibleSources Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:56 volumio-primo volumio[10703]: info: Listing playlists Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 20:05:56 volumio-primo volumio[10703]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:05:56 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:56 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:56 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:56 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::pushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::updateTrackBlock Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrackBlock Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::setRepeat true single undefined Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::pushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::setRandom false Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::pushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: Setting Device type: Tinkerboard Feb 15 20:05:57 volumio-primo volumio[10703]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreStateMachine::pushState Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:57 volumio-primo volumio[10703]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:05:57 volumio-primo volumio[10703]: info: Output device has changed, restarting MPD Feb 15 20:05:57 volumio-primo volumio[10703]: info: Output device has changed, restarting Shairport Sync Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo sudo[10899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:05:57 volumio-primo sudo[10899]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo sudo[10902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:05:57 volumio-primo sudo[10899]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:57 volumio-primo sudo[10902]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo volumio[10703]: info: ___________ START PLUGINS ___________ Feb 15 20:05:57 volumio-primo systemd[1]: Stopping Music Player Daemon... Feb 15 20:05:57 volumio-primo kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Feb 15 20:05:57 volumio-primo volumio[10703]: info: ControllerMpd::onStart: Initializing MPD Feb 15 20:05:57 volumio-primo volumio[10703]: info: Creating MPD Configuration file Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957421] CoreMusicLibrary::Adding element Server multimediali Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:57 volumio-primo sudo[10925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:05:57 volumio-primo sudo[10925]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo sudo[10928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo sudo[10925]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:57 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:05:57 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:05:57 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:05:57 volumio-primo sudo[10928]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957507] CoreMusicLibrary::Adding element Last_100 Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:57 volumio-primo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 15 20:05:57 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:05:57 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957511] CoreMusicLibrary::Adding element Webradio Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:57 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:57 volumio-primo volumio[10703]: info: Initializing BBC Radios Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: Loading i18n strings for locale it Feb 15 20:05:57 volumio-primo sudo[10948]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:05:57 volumio-primo volumio[10703]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: FusionDsp - mixtype--------------------- Hardware Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957580] CoreMusicLibrary::Adding element Pandora Radio Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:57 volumio-primo volumio[10703]: Cannot find translation for source Pandora Radio Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957583] ControllerPandora::initializeMQTT Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957583] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Feb 15 20:05:57 volumio-primo sudo[10948]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo volumio[10703]: info: Loading i18n strings for locale it Feb 15 20:05:57 volumio-primo volumio[10703]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:05:57 volumio-primo sudo[10948]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:57 volumio-primo volumio[10703]: info: Volumio Calling Home Feb 15 20:05:57 volumio-primo volumio[10703]: info: Preparing to generate the ALSA configuration file Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957684] ControllerPandora::initialSetup Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957686] ControllerPandora::PandoraHandler::init Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957687] ControllerPandora::PandoraHandler::loadErrorCodes Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957749] [Pandora] PandoraHandler::setMaxStationTracks: 16 Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957750] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957752] ControllerPandora::flushPandora Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::getQueue Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::clearAddPlayQueue Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::saveQueue Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 15 20:05:57 volumio-primo volumio[10703]: error: WARNING: No clearAddPlayTracks method for service webradio Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:05:57 volumio-primo sudo[10974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957760] ControllerPandora::validateAndSetAccountOptions Feb 15 20:05:57 volumio-primo volumio[10703]: error: [1739649957761] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Feb 15 20:05:57 volumio-primo sudo[10974]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo sudo[10974]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:57 volumio-primo volumio[10703]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:05:57 volumio-primo volumio[10703]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:05:57 volumio-primo volumio[10703]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:05:57 volumio-primo volumio[10703]: info: Reading ALSA contributions from plugins. Feb 15 20:05:57 volumio-primo volumio[10703]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Feb 15 20:05:57 volumio-primo volumio[10703]: info: MPD Permissions set Feb 15 20:05:57 volumio-primo volumio[10703]: info: MPD Permissions set Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetState Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:57 volumio-primo volumio[10703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 15 20:05:57 volumio-primo volumio[10703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:05:57 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:57 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:57 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:57 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:05:57 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:57 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:58 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:58 volumio-primo sudo[10996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:05:58 volumio-primo sudo[10998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetState Feb 15 20:05:58 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:58 volumio-primo sudo[10996]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetState Feb 15 20:05:58 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:58 volumio-primo sudo[10998]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo volumio[10703]: info: ir_controller: LIRC correctly updated. Feb 15 20:05:58 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:05:58 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:05:58 volumio-primo sudo[10996]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11004]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo volumio[10703]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:05:58 volumio-primo volumio[10703]: info: Output device has changed, restarting MPD Feb 15 20:05:58 volumio-primo sudo[10998]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo volumio[10703]: info: Output device has changed, restarting Shairport Sync Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:58 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11004]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo sudo[11025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:05:58 volumio-primo sudo[11027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:05:58 volumio-primo sudo[11025]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo sudo[11025]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo sudo[11027]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo volumio[10703]: info: Volumio called home Feb 15 20:05:58 volumio-primo volumio[10703]: info: MPD Permissions set Feb 15 20:05:58 volumio-primo volumio[10703]: info: Shairport-Sync Started Feb 15 20:05:58 volumio-primo volumio[10703]: Error adding Membership: Error: addMembership EINVAL Feb 15 20:05:58 volumio-primo volumio[10703]: info: Shairport-Sync Started Feb 15 20:05:58 volumio-primo volumio[10703]: info: Shairport-Sync Started Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:05:58 volumio-primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 15 20:05:58 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:05:58 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:58 volumio-primo sudo[11059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:05:58 volumio-primo sudo[11059]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11056]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:05:58 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11059]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo sudo[11056]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo sudo[11056]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo volumio[10703]: info: Shairport-Sync Started Feb 15 20:05:58 volumio-primo volumio[10703]: info: [ASDebug] Togle GPIO: OFF Feb 15 20:05:58 volumio-primo volumio[10703]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:05:58 volumio-primo volumio[10703]: Error: EPERM: operation not permitted, write Feb 15 20:05:58 volumio-primo volumio[10703]: at Object.writeSync (fs.js:737:3) Feb 15 20:05:58 volumio-primo volumio[10703]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Feb 15 20:05:58 volumio-primo volumio[10703]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Feb 15 20:05:58 volumio-primo volumio[10703]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Feb 15 20:05:58 volumio-primo volumio[10703]: at listOnTimeout (internal/timers.js:557:17) Feb 15 20:05:58 volumio-primo volumio[10703]: at processTimers (internal/timers.js:500:7) { Feb 15 20:05:58 volumio-primo volumio[10703]: errno: -1, Feb 15 20:05:58 volumio-primo volumio[10703]: syscall: 'write', Feb 15 20:05:58 volumio-primo volumio[10703]: code: 'EPERM' Feb 15 20:05:58 volumio-primo volumio[10703]: } Feb 15 20:05:58 volumio-primo volumio[10703]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:05:58 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:58 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:58 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:58 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:59 volumio-primo sudo[11090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 20:04 Feb 15 20:05:59 volumio-primo sudo[11090]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:59 volumio-primo sudo[11090]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:59 volumio-primo mpd[11079]: Feb 15 20:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 15 20:05:59 volumio-primo systemd[1]: Started Music Player Daemon. Feb 15 20:05:59 volumio-primo sudo[11027]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:59 volumio-primo sudo[10928]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:59 volumio-primo sudo[10902]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:59 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:59 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:59 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:59 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:00 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 15 20:06:00 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 15 20:06:00 volumio-primo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 15 20:06:00 volumio-primo systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 15 20:06:00 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:06:00 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:06:00 volumio-primo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 15 20:06:00 volumio-primo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Feb 15 20:06:00 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:06:00 volumio-primo systemd[1]: Stopped Volumio Backend Module. Feb 15 20:06:00 volumio-primo systemd[1]: Started Volumio Backend Module. Feb 15 20:06:00 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:06:00 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:00 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:00 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:00 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:01 volumio-primo volumio[11125]: info: ------------------------------------------- Feb 15 20:06:01 volumio-primo volumio[11125]: info: ----- Volumio3 ---- Feb 15 20:06:01 volumio-primo volumio[11125]: info: ------------------------------------------- Feb 15 20:06:01 volumio-primo volumio[11125]: info: ----- System startup ---- Feb 15 20:06:01 volumio-primo volumio[11125]: info: ------------------------------------------- Feb 15 20:06:01 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:01 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:01 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:01 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:01 volumio-primo volumio[11125]: info: MYVOLUMIO Environment detected Feb 15 20:06:02 volumio-primo volumio[11125]: info: Plugin folders cleanup Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning into folder /volumio/app/plugins/ Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category audio_interface Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category miscellanea Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category music_service Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category plugins.json Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category system_controller Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category user_interface Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning into folder /data/plugins/ Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category RoonBridge Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category audio_interface Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category music_service Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category system_controller Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category system_hardware Feb 15 20:06:02 volumio-primo volumio[11125]: info: Scanning category user_interface Feb 15 20:06:02 volumio-primo volumio[11125]: info: Plugin folders cleanup completed Feb 15 20:06:02 volumio-primo volumio[11125]: info: ------------------------------------------- Feb 15 20:06:02 volumio-primo volumio[11125]: info: ----- Core plugins startup ---- Feb 15 20:06:02 volumio-primo volumio[11125]: info: ------------------------------------------- Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugins from folder /volumio/app/plugins/ Feb 15 20:06:02 volumio-primo volumio[11125]: info: Adding plugin upnp to MyMusic Plugins Feb 15 20:06:02 volumio-primo volumio[11125]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 15 20:06:02 volumio-primo volumio[11125]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugins from folder /data/plugins/ Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "system"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "ampswitch"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Applying required configuration parameters for plugin ampswitch Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "appearance"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "network"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Refreshing Cached IP Addresses Feb 15 20:06:02 volumio-primo sudo[11152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:06:02 volumio-primo sudo[11152]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "services"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "alsa_controller"... Feb 15 20:06:02 volumio-primo sudo[11154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:06:02 volumio-primo sudo[11152]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:02 volumio-primo sudo[11166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 15 20:06:02 volumio-primo sudo[11154]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:02 volumio-primo sudo[11154]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:02 volumio-primo sudo[11166]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:02 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "wizard"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "networkfs"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Starting Udev Watcher for removable devices Feb 15 20:06:02 volumio-primo volumio[11125]: info: Ignoring mount for partition: BOOT Feb 15 20:06:02 volumio-primo volumio[11125]: info: Ignoring mount for partition: volumio Feb 15 20:06:02 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:02 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:02 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:02 volumio-primo volumio[11125]: info: Ignoring mount for partition: volumio_data Feb 15 20:06:02 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:02 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "volumio_command_line_client"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "upnp"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: [1739649962933] Starting Upmpd Daemon Feb 15 20:06:02 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "my_music"... Feb 15 20:06:02 volumio-primo volumio[11125]: info: Loading plugin "mpd"... Feb 15 20:06:03 volumio-primo sudo[11166]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "upnp_browser"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "alarm-clock"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "airplay_emulation"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Starting Shairport Sync Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "last_100"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "webradio"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "i2s_dacs"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: I2S DAC not set, start Auto-detection Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "volumiodiscovery"... Feb 15 20:06:03 volumio-primo volumio[11125]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:03 volumio-primo volumio[11125]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:03 volumio-primo volumio[11125]: *** WARNING *** For more information see Feb 15 20:06:03 volumio-primo volumio[11125]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:03 volumio-primo volumio[11125]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:03 volumio-primo node[11125]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:03 volumio-primo volumio[11125]: *** WARNING *** For more information see Feb 15 20:06:03 volumio-primo node[11125]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:03 volumio-primo node[11125]: *** WARNING *** For more information see Feb 15 20:06:03 volumio-primo node[11125]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:03 volumio-primo node[11125]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:03 volumio-primo node[11125]: *** WARNING *** For more information see Feb 15 20:06:03 volumio-primo volumio[11125]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 15 20:06:03 volumio-primo volumio[11125]: info: Discovery: Started advertising with name: Volumio primo Feb 15 20:06:03 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "outputs"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "albumart"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Plugin example_plugin is not enabled Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "inputs"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "updater_comm"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Plugin mpdemulation is not enabled Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "rest_api"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "websocket"... Feb 15 20:06:03 volumio-primo volumio[11125]: info: Starting Socket.io Server version 2.3.0 Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "fusiondsp"... Feb 15 20:06:03 volumio-primo volumio[11125]: Forking 3 albumart workers Feb 15 20:06:03 volumio-primo volumio[11125]: info: Applying required configuration parameters for plugin fusiondsp Feb 15 20:06:03 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:03 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:03 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:03 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:03 volumio-primo volumio[11125]: info: Loading plugin "pandora"... Feb 15 20:06:04 volumio-primo volumio[11125]: Starting albumart workers Feb 15 20:06:04 volumio-primo volumio[11125]: Starting albumart workers Feb 15 20:06:04 volumio-primo volumio[11125]: Starting albumart workers Feb 15 20:06:04 volumio-primo volumio[11125]: info: Applying required configuration parameters for plugin pandora Feb 15 20:06:04 volumio-primo volumio[11125]: info: Loading plugin "ir_controller"... Feb 15 20:06:04 volumio-primo volumio[11125]: info: Applying required configuration parameters for plugin ir_controller Feb 15 20:06:04 volumio-primo volumio[11125]: info: Loading plugin "rotaryencoder2"... Feb 15 20:06:04 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:04 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:04 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:04 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:05 volumio-primo volumio[11125]: info: Loading plugin "Systeminfo"... Feb 15 20:06:05 volumio-primo volumio[11125]: info: Loading i18n strings for locale it Feb 15 20:06:05 volumio-primo volumio[11125]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:05 volumio-primo volumio[11125]: Updating browse sources language Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::initPlayerControls Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:05 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:05] [connect] Successful connection Feb 15 20:06:05 volumio-primo volumio[11125]: Express server listening on port 3000 Feb 15 20:06:05 volumio-primo volumio[11125]: [Metrics] WebUI: 4s 59.39ms Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::resetVolumioState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::getcurrentVolume Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 15 20:06:05 volumio-primo volumio[11125]: info: Completed loading Core Plugins Feb 15 20:06:05 volumio-primo volumio[11125]: info: Preparing to generate the ALSA configuration file Feb 15 20:06:05 volumio-primo volumio[11125]: info: Volumio Network Manager: Network status updated: 3 Feb 15 20:06:05 volumio-primo volumio[11125]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:06:05 volumio-primo volumio[11125]: info: Reading ALSA contributions from plugins. Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Feb 15 20:06:05 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739649965 101 Feb 15 20:06:05 volumio-primo volumio[11125]: 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: 9 Feb 15 20:06:05 volumio-primo volumio[11125]: 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: 10 Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 15 20:06:05 volumio-primo volumio[11125]: info: Reloading queue from file Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::setRepeat true single undefined Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::pushState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::setRandom false Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::pushState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:05 volumio-primo volumio[11125]: info: Setting Device type: Tinkerboard Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:05 volumio-primo volumio[11125]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::pushState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::updateTrackBlock Feb 15 20:06:05 volumio-primo volumio[11125]: info: CorePlayQueue::getTrackBlock Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 20:06:05 volumio-primo volumio[11125]: info: Listing playlists Feb 15 20:06:05 volumio-primo volumio[11125]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:05 volumio-primo volumio[11125]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreStateMachine::pushState Feb 15 20:06:05 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:05 volumio-primo volumio[11125]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:06:05 volumio-primo volumio[11125]: info: Output device has changed, restarting MPD Feb 15 20:06:05 volumio-primo volumio[11125]: info: Output device has changed, restarting Shairport Sync Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:05 volumio-primo sudo[11276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:06:05 volumio-primo sudo[11278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:06:05 volumio-primo sudo[11276]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:05 volumio-primo sudo[11278]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:05 volumio-primo sudo[11276]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:05 volumio-primo volumio[11125]: info: ___________ START PLUGINS ___________ Feb 15 20:06:05 volumio-primo systemd[1]: Stopping Music Player Daemon... Feb 15 20:06:05 volumio-primo kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Feb 15 20:06:05 volumio-primo volumio[11125]: info: ControllerMpd::onStart: Initializing MPD Feb 15 20:06:05 volumio-primo volumio[11125]: info: Creating MPD Configuration file Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:05 volumio-primo volumio[11125]: info: [1739649965860] CoreMusicLibrary::Adding element Server multimediali Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:05 volumio-primo sudo[11301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:06:05 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:06:05 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:06:05 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:06:05 volumio-primo sudo[11304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:06:05 volumio-primo sudo[11301]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:05 volumio-primo sudo[11304]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:05 volumio-primo sudo[11301]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:05 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:05 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:05 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:05 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:05 volumio-primo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 15 20:06:05 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:06:05 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:06:05 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:05 volumio-primo volumio[11125]: info: [1739649965970] CoreMusicLibrary::Adding element Last_100 Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:05 volumio-primo volumio[11125]: info: [1739649965972] CoreMusicLibrary::Adding element Webradio Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:05 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:05 volumio-primo volumio[11125]: info: Initializing BBC Radios Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: Loading i18n strings for locale it Feb 15 20:06:06 volumio-primo sudo[11324]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:06:06 volumio-primo volumio[11125]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: FusionDsp - mixtype--------------------- Hardware Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966044] CoreMusicLibrary::Adding element Pandora Radio Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:06 volumio-primo volumio[11125]: Cannot find translation for source Pandora Radio Feb 15 20:06:06 volumio-primo sudo[11324]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966050] ControllerPandora::initializeMQTT Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966051] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Feb 15 20:06:06 volumio-primo volumio[11125]: info: Loading i18n strings for locale it Feb 15 20:06:06 volumio-primo sudo[11324]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:06 volumio-primo volumio[11125]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:06 volumio-primo volumio[11125]: info: Volumio Calling Home Feb 15 20:06:06 volumio-primo volumio[11125]: info: Preparing to generate the ALSA configuration file Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966150] ControllerPandora::initialSetup Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966152] ControllerPandora::PandoraHandler::init Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966152] ControllerPandora::PandoraHandler::loadErrorCodes Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966218] [Pandora] PandoraHandler::setMaxStationTracks: 16 Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966219] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966221] ControllerPandora::flushPandora Feb 15 20:06:06 volumio-primo volumio[11125]: info: CorePlayQueue::getQueue Feb 15 20:06:06 volumio-primo sudo[11350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Feb 15 20:06:06 volumio-primo volumio[11125]: info: CorePlayQueue::clearAddPlayQueue Feb 15 20:06:06 volumio-primo volumio[11125]: info: CorePlayQueue::saveQueue Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 15 20:06:06 volumio-primo volumio[11125]: error: WARNING: No clearAddPlayTracks method for service webradio Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:06:06 volumio-primo volumio[11125]: info: [1739649966228] ControllerPandora::validateAndSetAccountOptions Feb 15 20:06:06 volumio-primo volumio[11125]: error: [1739649966230] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Feb 15 20:06:06 volumio-primo sudo[11350]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:06 volumio-primo sudo[11350]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreStateMachine::getQueue Feb 15 20:06:06 volumio-primo volumio[11125]: info: CorePlayQueue::getQueue Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioGetVisibleSources Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:06 volumio-primo volumio[11125]: info: Listing playlists Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 20:06:06 volumio-primo volumio[11125]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:06:06 volumio-primo volumio[11125]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:06:06 volumio-primo volumio[11125]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:06:06 volumio-primo volumio[11125]: info: Reading ALSA contributions from plugins. Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:06:06 volumio-primo volumio[11125]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Feb 15 20:06:06 volumio-primo volumio[11125]: info: MPD Permissions set Feb 15 20:06:06 volumio-primo volumio[11125]: info: MPD Permissions set Feb 15 20:06:06 volumio-primo volumio[11125]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:06:06 volumio-primo volumio[11125]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:06:06 volumio-primo volumio[11125]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:06:06 volumio-primo volumio[11125]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:06 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:06 volumio-primo volumio[11125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 15 20:06:06 volumio-primo volumio[11125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 15 20:06:06 volumio-primo volumio[11125]: info: Volumio called home Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:06:06 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:06 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:06 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:06 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:06 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:07 volumio-primo volumio[11125]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:06:07 volumio-primo volumio[11125]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:06:07 volumio-primo volumio[11125]: info: Starting Shairport Sync Feb 15 20:06:07 volumio-primo volumio[11125]: info: Starting Shairport Sync Feb 15 20:06:07 volumio-primo volumio[11125]: info: Starting Shairport Sync Feb 15 20:06:07 volumio-primo sudo[11372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:07 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:07 volumio-primo sudo[11372]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:07 volumio-primo sudo[11376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:07 volumio-primo volumio[11125]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:07 volumio-primo volumio[11125]: info: ir_controller: LIRC correctly updated. Feb 15 20:06:07 volumio-primo sudo[11376]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:07 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:06:07 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:07 volumio-primo sudo[11379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:06:07 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:07 volumio-primo sudo[11372]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:07 volumio-primo sudo[11379]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:07 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:06:07 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Start request repeated too quickly. Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Feb 15 20:06:07 volumio-primo volumio[11125]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:06:07 volumio-primo systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:07 volumio-primo volumio[11125]: info: Output device has changed, restarting MPD Feb 15 20:06:07 volumio-primo sudo[11376]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Start request repeated too quickly. Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Feb 15 20:06:07 volumio-primo systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:07 volumio-primo sudo[11379]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:07 volumio-primo volumio[11125]: info: Output device has changed, restarting Shairport Sync Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:07 volumio-primo sudo[11403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:06:07 volumio-primo sudo[11403]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:07 volumio-primo sudo[11403]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:07 volumio-primo sudo[11410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:06:07 volumio-primo sudo[11410]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:07 volumio-primo volumio[11125]: info: MPD Permissions set Feb 15 20:06:07 volumio-primo volumio[11125]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Feb 15 20:06:07 volumio-primo volumio[11125]: Job for shairport-sync.service failed. Feb 15 20:06:07 volumio-primo volumio[11125]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Feb 15 20:06:07 volumio-primo volumio[11125]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Feb 15 20:06:07 volumio-primo volumio[11125]: Job for shairport-sync.service failed. Feb 15 20:06:07 volumio-primo volumio[11125]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Feb 15 20:06:07 volumio-primo volumio[11125]: info: Shairport-Sync Started Feb 15 20:06:07 volumio-primo volumio[11125]: Error adding Membership: Error: addMembership EINVAL Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:06:07 volumio-primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 15 20:06:07 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:06:07 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:06:07 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:06:07 volumio-primo volumio[11125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:07 volumio-primo volumio[11125]: info: Starting Shairport Sync Feb 15 20:06:07 volumio-primo sudo[11432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:06:07 volumio-primo sudo[11432]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Start request repeated too quickly. Feb 15 20:06:07 volumio-primo systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Feb 15 20:06:07 volumio-primo systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:07 volumio-primo sudo[11429]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:06:07 volumio-primo sudo[11432]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:07 volumio-primo sudo[11429]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:07 volumio-primo volumio[11125]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Feb 15 20:06:07 volumio-primo volumio[11125]: Job for shairport-sync.service failed. Feb 15 20:06:07 volumio-primo volumio[11125]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Feb 15 20:06:07 volumio-primo sudo[11429]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:07 volumio-primo volumio[11125]: info: [ASDebug] Togle GPIO: OFF Feb 15 20:06:07 volumio-primo volumio[11125]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:06:07 volumio-primo volumio[11125]: Error: EPERM: operation not permitted, write Feb 15 20:06:07 volumio-primo volumio[11125]: at Object.writeSync (fs.js:737:3) Feb 15 20:06:07 volumio-primo volumio[11125]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Feb 15 20:06:07 volumio-primo volumio[11125]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Feb 15 20:06:07 volumio-primo volumio[11125]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Feb 15 20:06:07 volumio-primo volumio[11125]: at listOnTimeout (internal/timers.js:557:17) Feb 15 20:06:07 volumio-primo volumio[11125]: at processTimers (internal/timers.js:500:7) { Feb 15 20:06:07 volumio-primo volumio[11125]: errno: -1, Feb 15 20:06:07 volumio-primo volumio[11125]: syscall: 'write', Feb 15 20:06:07 volumio-primo volumio[11125]: code: 'EPERM' Feb 15 20:06:07 volumio-primo volumio[11125]: } Feb 15 20:06:07 volumio-primo volumio[11125]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:06:07 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:07 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:07 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:07 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:08 volumio-primo sudo[11459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 20:05 Feb 15 20:06:08 volumio-primo sudo[11459]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:08 volumio-primo sudo[11459]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:08 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 15 20:06:08 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 15 20:06:08 volumio-primo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 15 20:06:08 volumio-primo sudo[11304]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:08 volumio-primo sudo[11410]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:08 volumio-primo sudo[11278]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:08 volumio-primo systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 15 20:06:08 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:06:08 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:06:08 volumio-primo mpd[11449]: Feb 15 20:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 15 20:06:08 volumio-primo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 15 20:06:08 volumio-primo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Feb 15 20:06:08 volumio-primo systemd[1]: Started Music Player Daemon. Feb 15 20:06:08 volumio-primo systemd[1]: Stopped Volumio Backend Module. Feb 15 20:06:08 volumio-primo systemd[1]: Started Volumio Backend Module. Feb 15 20:06:08 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:06:08 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:06:08 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:08 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:08 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:08 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:09 volumio-primo volumio[11487]: info: ------------------------------------------- Feb 15 20:06:09 volumio-primo volumio[11487]: info: ----- Volumio3 ---- Feb 15 20:06:09 volumio-primo volumio[11487]: info: ------------------------------------------- Feb 15 20:06:09 volumio-primo volumio[11487]: info: ----- System startup ---- Feb 15 20:06:09 volumio-primo volumio[11487]: info: ------------------------------------------- Feb 15 20:06:09 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:09 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:09 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:09 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:10 volumio-primo volumio[11487]: info: MYVOLUMIO Environment detected Feb 15 20:06:10 volumio-primo volumio[11487]: info: Plugin folders cleanup Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning into folder /volumio/app/plugins/ Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category audio_interface Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category miscellanea Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category music_service Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category plugins.json Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category system_controller Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category user_interface Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning into folder /data/plugins/ Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category RoonBridge Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category audio_interface Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category music_service Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category system_controller Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category system_hardware Feb 15 20:06:10 volumio-primo volumio[11487]: info: Scanning category user_interface Feb 15 20:06:10 volumio-primo volumio[11487]: info: Plugin folders cleanup completed Feb 15 20:06:10 volumio-primo volumio[11487]: info: ------------------------------------------- Feb 15 20:06:10 volumio-primo volumio[11487]: info: ----- Core plugins startup ---- Feb 15 20:06:10 volumio-primo volumio[11487]: info: ------------------------------------------- Feb 15 20:06:10 volumio-primo volumio[11487]: info: Loading plugins from folder /volumio/app/plugins/ Feb 15 20:06:10 volumio-primo volumio[11487]: info: Adding plugin upnp to MyMusic Plugins Feb 15 20:06:10 volumio-primo volumio[11487]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 15 20:06:10 volumio-primo volumio[11487]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 15 20:06:10 volumio-primo volumio[11487]: info: Loading plugins from folder /data/plugins/ Feb 15 20:06:10 volumio-primo volumio[11487]: info: Loading plugin "system"... Feb 15 20:06:10 volumio-primo volumio[11487]: info: Loading plugin "ampswitch"... Feb 15 20:06:10 volumio-primo volumio[11487]: info: Applying required configuration parameters for plugin ampswitch Feb 15 20:06:10 volumio-primo volumio[11487]: info: Loading plugin "appearance"... Feb 15 20:06:10 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:10 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:10 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:10 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "network"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Refreshing Cached IP Addresses Feb 15 20:06:11 volumio-primo sudo[11518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:06:11 volumio-primo sudo[11520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "services"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "alsa_controller"... Feb 15 20:06:11 volumio-primo sudo[11518]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:11 volumio-primo sudo[11520]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:11 volumio-primo sudo[11518]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:11 volumio-primo sudo[11520]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:11 volumio-primo sudo[11532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 15 20:06:11 volumio-primo sudo[11532]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:11 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "wizard"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "networkfs"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Starting Udev Watcher for removable devices Feb 15 20:06:11 volumio-primo volumio[11487]: info: Ignoring mount for partition: BOOT Feb 15 20:06:11 volumio-primo volumio[11487]: info: Ignoring mount for partition: volumio Feb 15 20:06:11 volumio-primo volumio[11487]: info: Ignoring mount for partition: volumio_data Feb 15 20:06:11 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "volumio_command_line_client"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "upnp"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: [1739649971327] Starting Upmpd Daemon Feb 15 20:06:11 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "my_music"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "mpd"... Feb 15 20:06:11 volumio-primo sudo[11532]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "upnp_browser"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "alarm-clock"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "airplay_emulation"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Starting Shairport Sync Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "last_100"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "webradio"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "i2s_dacs"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: I2S DAC not set, start Auto-detection Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "volumiodiscovery"... Feb 15 20:06:11 volumio-primo volumio[11487]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:11 volumio-primo volumio[11487]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:11 volumio-primo volumio[11487]: *** WARNING *** For more information see Feb 15 20:06:11 volumio-primo volumio[11487]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:11 volumio-primo volumio[11487]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:11 volumio-primo node[11487]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:11 volumio-primo volumio[11487]: *** WARNING *** For more information see Feb 15 20:06:11 volumio-primo node[11487]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:11 volumio-primo node[11487]: *** WARNING *** For more information see Feb 15 20:06:11 volumio-primo node[11487]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:11 volumio-primo node[11487]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:11 volumio-primo node[11487]: *** WARNING *** For more information see Feb 15 20:06:11 volumio-primo volumio[11487]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 15 20:06:11 volumio-primo volumio[11487]: info: Discovery: Started advertising with name: Volumio primo Feb 15 20:06:11 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "outputs"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "albumart"... Feb 15 20:06:11 volumio-primo volumio[11487]: info: Plugin example_plugin is not enabled Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "inputs"... Feb 15 20:06:11 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:11 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:11 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:11 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:11 volumio-primo volumio[11487]: info: Loading plugin "updater_comm"... Feb 15 20:06:12 volumio-primo volumio[11487]: info: Plugin mpdemulation is not enabled Feb 15 20:06:12 volumio-primo volumio[11487]: info: Loading plugin "rest_api"... Feb 15 20:06:12 volumio-primo volumio[11487]: info: Loading plugin "websocket"... Feb 15 20:06:12 volumio-primo volumio[11487]: info: Starting Socket.io Server version 2.3.0 Feb 15 20:06:12 volumio-primo volumio[11487]: info: Loading plugin "fusiondsp"... Feb 15 20:06:12 volumio-primo volumio[11487]: info: Applying required configuration parameters for plugin fusiondsp Feb 15 20:06:12 volumio-primo volumio[11487]: Forking 3 albumart workers Feb 15 20:06:12 volumio-primo volumio[11487]: info: Loading plugin "pandora"... Feb 15 20:06:12 volumio-primo volumio[11487]: Starting albumart workers Feb 15 20:06:12 volumio-primo volumio[11487]: Starting albumart workers Feb 15 20:06:12 volumio-primo volumio[11487]: Starting albumart workers Feb 15 20:06:12 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:12 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:12 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:12 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:13 volumio-primo volumio[11487]: info: Applying required configuration parameters for plugin pandora Feb 15 20:06:13 volumio-primo volumio[11487]: info: Loading plugin "ir_controller"... Feb 15 20:06:13 volumio-primo volumio[11487]: info: Applying required configuration parameters for plugin ir_controller Feb 15 20:06:13 volumio-primo volumio[11487]: info: Loading plugin "rotaryencoder2"... Feb 15 20:06:13 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:13] [connect] Successful connection Feb 15 20:06:13 volumio-primo volumio[11487]: info: Loading plugin "Systeminfo"... Feb 15 20:06:13 volumio-primo volumio[11487]: info: Loading i18n strings for locale it Feb 15 20:06:13 volumio-primo volumio[11487]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:13 volumio-primo volumio[11487]: Updating browse sources language Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::initPlayerControls Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: Express server listening on port 3000 Feb 15 20:06:13 volumio-primo volumio[11487]: [Metrics] WebUI: 4s 417.25ms Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreStateMachine::resetVolumioState Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreStateMachine::getcurrentVolume Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:06:13 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:13 volumio-primo volumio[11487]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 15 20:06:13 volumio-primo volumio[11487]: info: Completed loading Core Plugins Feb 15 20:06:13 volumio-primo volumio[11487]: info: Preparing to generate the ALSA configuration file Feb 15 20:06:13 volumio-primo volumio[11487]: info: Volumio Network Manager: Network status updated: 3 Feb 15 20:06:13 volumio-primo volumio[11487]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:06:13 volumio-primo volumio[11487]: info: Reading ALSA contributions from plugins. Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Feb 15 20:06:13 volumio-primo volumio[11487]: 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: 3 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Feb 15 20:06:13 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739649973 101 Feb 15 20:06:13 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:13 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:13 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:13 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:13 volumio-primo volumio[11487]: 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: 10 Feb 15 20:06:13 volumio-primo volumio[11487]: 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: 11 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Feb 15 20:06:13 volumio-primo volumio[11487]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 15 20:06:14 volumio-primo volumio[11487]: info: Reloading queue from file Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreStateMachine::setRepeat true single undefined Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreStateMachine::pushState Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreStateMachine::setRandom false Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreStateMachine::pushState Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:14 volumio-primo volumio[11487]: info: Setting Device type: Tinkerboard Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 20:06:14 volumio-primo volumio[11487]: info: Listing playlists Feb 15 20:06:14 volumio-primo volumio[11487]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreStateMachine::pushState Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreStateMachine::updateTrackBlock Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getTrackBlock Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreStateMachine::getQueue Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getQueue Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioGetVisibleSources Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:14 volumio-primo volumio[11487]: info: Listing playlists Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:06:14 volumio-primo volumio[11487]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreStateMachine::pushState Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:14 volumio-primo volumio[11487]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:06:14 volumio-primo volumio[11487]: info: Output device has changed, restarting MPD Feb 15 20:06:14 volumio-primo volumio[11487]: info: Output device has changed, restarting Shairport Sync Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:14 volumio-primo sudo[11645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:06:14 volumio-primo sudo[11648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:06:14 volumio-primo sudo[11645]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:14 volumio-primo sudo[11648]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:14 volumio-primo sudo[11645]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:14 volumio-primo volumio[11487]: info: ___________ START PLUGINS ___________ Feb 15 20:06:14 volumio-primo systemd[1]: Stopping Music Player Daemon... Feb 15 20:06:14 volumio-primo kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Feb 15 20:06:14 volumio-primo volumio[11487]: info: ControllerMpd::onStart: Initializing MPD Feb 15 20:06:14 volumio-primo volumio[11487]: info: Creating MPD Configuration file Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:14 volumio-primo sudo[11670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974522] CoreMusicLibrary::Adding element Server multimediali Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:14 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:06:14 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:06:14 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:06:14 volumio-primo sudo[11670]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:14 volumio-primo sudo[11673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:06:14 volumio-primo sudo[11670]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:14 volumio-primo sudo[11673]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:14 volumio-primo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 15 20:06:14 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:06:14 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:06:14 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974667] CoreMusicLibrary::Adding element Last_100 Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974669] CoreMusicLibrary::Adding element Webradio Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:14 volumio-primo volumio[11487]: info: Initializing BBC Radios Feb 15 20:06:14 volumio-primo sudo[11693]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:06:14 volumio-primo sudo[11693]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:14 volumio-primo sudo[11693]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:14 volumio-primo volumio[11487]: info: Loading i18n strings for locale it Feb 15 20:06:14 volumio-primo volumio[11487]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:14 volumio-primo volumio[11487]: info: FusionDsp - mixtype--------------------- Hardware Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974741] CoreMusicLibrary::Adding element Pandora Radio Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:14 volumio-primo volumio[11487]: Cannot find translation for source Pandora Radio Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974743] ControllerPandora::initializeMQTT Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974744] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Feb 15 20:06:14 volumio-primo volumio[11487]: info: Loading i18n strings for locale it Feb 15 20:06:14 volumio-primo volumio[11487]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:14 volumio-primo volumio[11487]: info: Volumio Calling Home Feb 15 20:06:14 volumio-primo volumio[11487]: info: Preparing to generate the ALSA configuration file Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974849] ControllerPandora::initialSetup Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974852] ControllerPandora::PandoraHandler::init Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974852] ControllerPandora::PandoraHandler::loadErrorCodes Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974915] [Pandora] PandoraHandler::setMaxStationTracks: 16 Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974916] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974918] ControllerPandora::flushPandora Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::getQueue Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::clearAddPlayQueue Feb 15 20:06:14 volumio-primo volumio[11487]: info: CorePlayQueue::saveQueue Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 15 20:06:14 volumio-primo volumio[11487]: error: WARNING: No clearAddPlayTracks method for service webradio Feb 15 20:06:14 volumio-primo volumio[11487]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:06:14 volumio-primo sudo[11719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Feb 15 20:06:14 volumio-primo volumio[11487]: info: [1739649974928] ControllerPandora::validateAndSetAccountOptions Feb 15 20:06:14 volumio-primo volumio[11487]: error: [1739649974929] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Feb 15 20:06:14 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:14 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:14 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:14 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:14 volumio-primo sudo[11719]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:14 volumio-primo sudo[11719]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:15 volumio-primo volumio[11487]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:06:15 volumio-primo volumio[11487]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:06:15 volumio-primo volumio[11487]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:06:15 volumio-primo volumio[11487]: info: Reading ALSA contributions from plugins. Feb 15 20:06:15 volumio-primo volumio[11487]: info: Enabling MyMusic plugin bluetooth Feb 15 20:06:15 volumio-primo volumio[11487]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Feb 15 20:06:15 volumio-primo volumio[11487]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Feb 15 20:06:15 volumio-primo volumio[11487]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:06:15 volumio-primo volumio[11487]: TypeError: Cannot read property 'toUpperCase' of undefined Feb 15 20:06:15 volumio-primo volumio[11487]: at MyVolumioPluginManager.pushMyVolumioRequiredAccountModal (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:19516) Feb 15 20:06:15 volumio-primo volumio[11487]: at MyVolumioPluginManager.enablePlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:18418) Feb 15 20:06:15 volumio-primo volumio[11487]: at MyVolumioPluginManager.enableAndStartPlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:17039) Feb 15 20:06:15 volumio-primo volumio[11487]: at PluginManager.enableDisableMyMusicPlugin (/volumio/app/pluginmanager.js:2024:48) Feb 15 20:06:15 volumio-primo volumio[11487]: at CoreCommandRouter.enableDisableMyMusicPlugin (/volumio/app/index.js:2102:29) Feb 15 20:06:15 volumio-primo volumio[11487]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1929:59) Feb 15 20:06:15 volumio-primo volumio[11487]: at Socket.emit (events.js:400:28) Feb 15 20:06:15 volumio-primo volumio[11487]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 15 20:06:15 volumio-primo volumio[11487]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Feb 15 20:06:15 volumio-primo volumio[11487]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:06:15 volumio-primo sudo[11738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 20:05 Feb 15 20:06:15 volumio-primo sudo[11738]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:15 volumio-primo sudo[11738]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:15 volumio-primo mpd[11714]: Feb 15 20:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 15 20:06:15 volumio-primo systemd[1]: Started Music Player Daemon. Feb 15 20:06:15 volumio-primo sudo[11648]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:15 volumio-primo sudo[11673]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:15 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:15 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:15 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:15 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:16 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 15 20:06:16 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 15 20:06:16 volumio-primo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 15 20:06:16 volumio-primo systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 15 20:06:16 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:06:16 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:06:16 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:16 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:16 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:16 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:17 volumio-primo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 15 20:06:17 volumio-primo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Feb 15 20:06:17 volumio-primo systemd[1]: Stopped Volumio Backend Module. Feb 15 20:06:17 volumio-primo systemd[1]: Started Volumio Backend Module. Feb 15 20:06:17 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:06:17 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:06:17 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:17 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:17 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:17 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:18 volumio-primo volumio[11766]: info: ------------------------------------------- Feb 15 20:06:18 volumio-primo volumio[11766]: info: ----- Volumio3 ---- Feb 15 20:06:18 volumio-primo volumio[11766]: info: ------------------------------------------- Feb 15 20:06:18 volumio-primo volumio[11766]: info: ----- System startup ---- Feb 15 20:06:18 volumio-primo volumio[11766]: info: ------------------------------------------- Feb 15 20:06:18 volumio-primo volumio[11766]: info: MYVOLUMIO Environment detected Feb 15 20:06:18 volumio-primo volumio[11766]: info: Plugin folders cleanup Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning into folder /volumio/app/plugins/ Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category audio_interface Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category miscellanea Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category music_service Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category plugins.json Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category system_controller Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category user_interface Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning into folder /data/plugins/ Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category RoonBridge Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category audio_interface Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category music_service Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category system_controller Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category system_hardware Feb 15 20:06:18 volumio-primo volumio[11766]: info: Scanning category user_interface Feb 15 20:06:18 volumio-primo volumio[11766]: info: Plugin folders cleanup completed Feb 15 20:06:18 volumio-primo volumio[11766]: info: ------------------------------------------- Feb 15 20:06:18 volumio-primo volumio[11766]: info: ----- Core plugins startup ---- Feb 15 20:06:18 volumio-primo volumio[11766]: info: ------------------------------------------- Feb 15 20:06:18 volumio-primo volumio[11766]: info: Loading plugins from folder /volumio/app/plugins/ Feb 15 20:06:18 volumio-primo volumio[11766]: info: Adding plugin upnp to MyMusic Plugins Feb 15 20:06:18 volumio-primo volumio[11766]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 15 20:06:18 volumio-primo volumio[11766]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 15 20:06:18 volumio-primo volumio[11766]: info: Loading plugins from folder /data/plugins/ Feb 15 20:06:18 volumio-primo volumio[11766]: info: Loading plugin "system"... Feb 15 20:06:18 volumio-primo volumio[11766]: info: Loading plugin "ampswitch"... Feb 15 20:06:18 volumio-primo volumio[11766]: info: Applying required configuration parameters for plugin ampswitch Feb 15 20:06:18 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:18 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:18 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:18 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "appearance"... Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "network"... Feb 15 20:06:19 volumio-primo volumio[11766]: info: Refreshing Cached IP Addresses Feb 15 20:06:19 volumio-primo sudo[11794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:06:19 volumio-primo sudo[11796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "services"... Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "alsa_controller"... Feb 15 20:06:19 volumio-primo sudo[11796]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:19 volumio-primo sudo[11794]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:19 volumio-primo sudo[11794]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:19 volumio-primo sudo[11796]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:19 volumio-primo sudo[11813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 15 20:06:19 volumio-primo sudo[11813]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:19 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "wizard"... Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "networkfs"... Feb 15 20:06:19 volumio-primo volumio[11766]: info: Starting Udev Watcher for removable devices Feb 15 20:06:19 volumio-primo volumio[11766]: info: Ignoring mount for partition: BOOT Feb 15 20:06:19 volumio-primo volumio[11766]: info: Ignoring mount for partition: volumio Feb 15 20:06:19 volumio-primo volumio[11766]: info: Ignoring mount for partition: volumio_data Feb 15 20:06:19 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "volumio_command_line_client"... Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "upnp"... Feb 15 20:06:19 volumio-primo volumio[11766]: info: [1739649979855] Starting Upmpd Daemon Feb 15 20:06:19 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "my_music"... Feb 15 20:06:19 volumio-primo volumio[11766]: info: Loading plugin "mpd"... Feb 15 20:06:19 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:19 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:19 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:19 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "upnp_browser"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "alarm-clock"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "airplay_emulation"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Starting Shairport Sync Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "last_100"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "webradio"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "i2s_dacs"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: I2S DAC not set, start Auto-detection Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "volumiodiscovery"... Feb 15 20:06:20 volumio-primo volumio[11766]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:20 volumio-primo volumio[11766]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:20 volumio-primo volumio[11766]: *** WARNING *** For more information see Feb 15 20:06:20 volumio-primo volumio[11766]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:20 volumio-primo volumio[11766]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:20 volumio-primo node[11766]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:20 volumio-primo volumio[11766]: *** WARNING *** For more information see Feb 15 20:06:20 volumio-primo node[11766]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:20 volumio-primo node[11766]: *** WARNING *** For more information see Feb 15 20:06:20 volumio-primo node[11766]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:06:20 volumio-primo node[11766]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:06:20 volumio-primo node[11766]: *** WARNING *** For more information see Feb 15 20:06:20 volumio-primo volumio[11766]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 15 20:06:20 volumio-primo volumio[11766]: info: Discovery: Started advertising with name: Volumio primo Feb 15 20:06:20 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "outputs"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "albumart"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Plugin example_plugin is not enabled Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "inputs"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "updater_comm"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Plugin mpdemulation is not enabled Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "rest_api"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "websocket"... Feb 15 20:06:20 volumio-primo volumio[11766]: info: Starting Socket.io Server version 2.3.0 Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "fusiondsp"... Feb 15 20:06:20 volumio-primo volumio[11766]: Forking 3 albumart workers Feb 15 20:06:20 volumio-primo volumio[11766]: info: Applying required configuration parameters for plugin fusiondsp Feb 15 20:06:20 volumio-primo volumio[11766]: info: Loading plugin "pandora"... Feb 15 20:06:20 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:20 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:20 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:20 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:21 volumio-primo sudo[11813]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:21 volumio-primo volumio[11766]: Starting albumart workers Feb 15 20:06:21 volumio-primo volumio[11766]: Starting albumart workers Feb 15 20:06:21 volumio-primo volumio[11766]: Starting albumart workers Feb 15 20:06:21 volumio-primo volumio[11766]: info: Applying required configuration parameters for plugin pandora Feb 15 20:06:21 volumio-primo volumio[11766]: info: Loading plugin "ir_controller"... Feb 15 20:06:21 volumio-primo volumio[11766]: info: Applying required configuration parameters for plugin ir_controller Feb 15 20:06:21 volumio-primo volumio[11766]: info: Loading plugin "rotaryencoder2"... Feb 15 20:06:21 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:21] [connect] Successful connection Feb 15 20:06:21 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:21 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:21 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:21 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:21 volumio-primo volumio[11766]: info: Loading plugin "Systeminfo"... Feb 15 20:06:22 volumio-primo volumio[11766]: info: Loading i18n strings for locale it Feb 15 20:06:22 volumio-primo volumio[11766]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:22 volumio-primo volumio[11766]: Updating browse sources language Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::initPlayerControls Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: Express server listening on port 3000 Feb 15 20:06:22 volumio-primo volumio[11766]: [Metrics] WebUI: 4s 374.57ms Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::resetVolumioState Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::getcurrentVolume Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 15 20:06:22 volumio-primo volumio[11766]: info: Completed loading Core Plugins Feb 15 20:06:22 volumio-primo volumio[11766]: info: Preparing to generate the ALSA configuration file Feb 15 20:06:22 volumio-primo volumio[11766]: info: Volumio Network Manager: Network status updated: 3 Feb 15 20:06:22 volumio-primo volumio[11766]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:06:22 volumio-primo volumio[11766]: info: Reading ALSA contributions from plugins. Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Feb 15 20:06:22 volumio-primo volumio[11766]: 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: 2 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Feb 15 20:06:22 volumio-primo volumio[11766]: 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: 6 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Feb 15 20:06:22 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:06:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739649981 101 Feb 15 20:06:22 volumio-primo volumio[11766]: 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: 11 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 15 20:06:22 volumio-primo volumio[11766]: info: Reloading queue from file Feb 15 20:06:22 volumio-primo volumio[11766]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::setRepeat true single undefined Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::pushState Feb 15 20:06:22 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::setRandom false Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::pushState Feb 15 20:06:22 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:22 volumio-primo volumio[11766]: info: Setting Device type: Tinkerboard Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:22 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:22 volumio-primo volumio[11766]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::pushState Feb 15 20:06:22 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::updateTrackBlock Feb 15 20:06:22 volumio-primo volumio[11766]: info: CorePlayQueue::getTrackBlock Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::getQueue Feb 15 20:06:22 volumio-primo volumio[11766]: info: CorePlayQueue::getQueue Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioGetVisibleSources Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:22 volumio-primo volumio[11766]: info: Listing playlists Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 20:06:22 volumio-primo volumio[11766]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreStateMachine::pushState Feb 15 20:06:22 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioPushState Feb 15 20:06:22 volumio-primo volumio[11766]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:06:22 volumio-primo volumio[11766]: info: Output device has changed, restarting MPD Feb 15 20:06:22 volumio-primo volumio[11766]: info: Output device has changed, restarting Shairport Sync Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:22 volumio-primo sudo[11923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:06:22 volumio-primo sudo[11923]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:22 volumio-primo sudo[11927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:06:22 volumio-primo sudo[11923]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:22 volumio-primo sudo[11927]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:22 volumio-primo volumio[11766]: info: ___________ START PLUGINS ___________ Feb 15 20:06:22 volumio-primo systemd[1]: Stopping Music Player Daemon... Feb 15 20:06:22 volumio-primo kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Feb 15 20:06:22 volumio-primo volumio[11766]: info: ControllerMpd::onStart: Initializing MPD Feb 15 20:06:22 volumio-primo volumio[11766]: info: Creating MPD Configuration file Feb 15 20:06:22 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:06:22 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:06:22 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:22 volumio-primo volumio[11766]: info: [1739649982859] CoreMusicLibrary::Adding element Server multimediali Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:22 volumio-primo sudo[11948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:06:22 volumio-primo sudo[11948]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:22 volumio-primo sudo[11951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:22 volumio-primo sudo[11948]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:22 volumio-primo sudo[11951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:22 volumio-primo volumio[11766]: info: [1739649982945] CoreMusicLibrary::Adding element Last_100 Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:22 volumio-primo volumio[11766]: info: [1739649982947] CoreMusicLibrary::Adding element Webradio Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:22 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:22 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:22 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:22 volumio-primo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 15 20:06:22 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:22 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:06:22 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:22 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:06:22 volumio-primo volumio[11766]: info: Initializing BBC Radios Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:22 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: Loading i18n strings for locale it Feb 15 20:06:23 volumio-primo volumio[11766]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: FusionDsp - mixtype--------------------- Hardware Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983013] CoreMusicLibrary::Adding element Pandora Radio Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:06:23 volumio-primo volumio[11766]: Cannot find translation for source Pandora Radio Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983015] ControllerPandora::initializeMQTT Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983016] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Feb 15 20:06:23 volumio-primo volumio[11766]: info: Loading i18n strings for locale it Feb 15 20:06:23 volumio-primo sudo[11971]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:06:23 volumio-primo volumio[11766]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:06:23 volumio-primo sudo[11971]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:23 volumio-primo sudo[11971]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:23 volumio-primo volumio[11766]: info: Volumio Calling Home Feb 15 20:06:23 volumio-primo volumio[11766]: info: Preparing to generate the ALSA configuration file Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983107] ControllerPandora::initialSetup Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983108] ControllerPandora::PandoraHandler::init Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983109] ControllerPandora::PandoraHandler::loadErrorCodes Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983170] [Pandora] PandoraHandler::setMaxStationTracks: 16 Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983171] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983173] ControllerPandora::flushPandora Feb 15 20:06:23 volumio-primo volumio[11766]: info: CorePlayQueue::getQueue Feb 15 20:06:23 volumio-primo volumio[11766]: info: CorePlayQueue::clearAddPlayQueue Feb 15 20:06:23 volumio-primo volumio[11766]: info: CorePlayQueue::saveQueue Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 15 20:06:23 volumio-primo volumio[11766]: error: WARNING: No clearAddPlayTracks method for service webradio Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:06:23 volumio-primo volumio[11766]: info: [1739649983180] ControllerPandora::validateAndSetAccountOptions Feb 15 20:06:23 volumio-primo volumio[11766]: error: [1739649983181] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Feb 15 20:06:23 volumio-primo sudo[11997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Feb 15 20:06:23 volumio-primo sudo[11997]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:23 volumio-primo sudo[11997]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:23 volumio-primo volumio[11766]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:06:23 volumio-primo volumio[11766]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:06:23 volumio-primo volumio[11766]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:06:23 volumio-primo volumio[11766]: info: Reading ALSA contributions from plugins. Feb 15 20:06:23 volumio-primo volumio[11766]: 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: 15 Feb 15 20:06:23 volumio-primo volumio[11766]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Feb 15 20:06:23 volumio-primo volumio[11766]: info: MPD Permissions set Feb 15 20:06:23 volumio-primo volumio[11766]: info: MPD Permissions set Feb 15 20:06:23 volumio-primo volumio[11766]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:06:23 volumio-primo volumio[11766]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:06:23 volumio-primo volumio[11766]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:06:23 volumio-primo volumio[11766]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:06:23 volumio-primo volumio[11766]: info: Volumio called home Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:23 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:23 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:23 volumio-primo volumio[11766]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Feb 15 20:06:23 volumio-primo volumio[11766]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 20:06:23 volumio-primo volumio[11766]: info: Listing playlists Feb 15 20:06:23 volumio-primo volumio[11766]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:06:23 volumio-primo volumio[11766]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:06:23 volumio-primo volumio[11766]: info: Starting Shairport Sync Feb 15 20:06:23 volumio-primo volumio[11766]: info: Starting Shairport Sync Feb 15 20:06:23 volumio-primo volumio[11766]: info: Starting Shairport Sync Feb 15 20:06:23 volumio-primo sudo[12019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:06:23 volumio-primo sudo[12021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:23 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:23 volumio-primo sudo[12021]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:23 volumio-primo sudo[12026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:06:23 volumio-primo sudo[12019]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:23 volumio-primo volumio[11766]: info: CoreCommandRouter::volumioGetState Feb 15 20:06:23 volumio-primo volumio[11766]: info: CorePlayQueue::getTrack 0 Feb 15 20:06:23 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:23 volumio-primo sudo[12026]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:23 volumio-primo sudo[12021]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:23 volumio-primo volumio[11766]: info: ir_controller: LIRC correctly updated. Feb 15 20:06:23 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:06:23 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:06:23 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:23 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:23 volumio-primo sudo[12019]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:23 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:23 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:23 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:23 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:23 volumio-primo volumio[11766]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:06:23 volumio-primo volumio[11766]: info: Output device has changed, restarting MPD Feb 15 20:06:23 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:06:23 volumio-primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 15 20:06:23 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:06:23 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:23 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:23 volumio-primo sudo[12026]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:24 volumio-primo sudo[12050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:06:24 volumio-primo volumio[11766]: info: Output device has changed, restarting Shairport Sync Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:24 volumio-primo sudo[12050]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:24 volumio-primo sudo[12058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:06:24 volumio-primo sudo[12050]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:24 volumio-primo sudo[12058]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:24 volumio-primo volumio[11766]: info: Shairport-Sync Started Feb 15 20:06:24 volumio-primo volumio[11766]: Error adding Membership: Error: addMembership EINVAL Feb 15 20:06:24 volumio-primo volumio[11766]: info: MPD Permissions set Feb 15 20:06:24 volumio-primo volumio[11766]: info: Shairport-Sync Started Feb 15 20:06:24 volumio-primo volumio[11766]: info: Shairport-Sync Started Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 15 20:06:24 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:06:24 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:06:24 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:06:24 volumio-primo volumio[11766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:06:24 volumio-primo volumio[11766]: info: Starting Shairport Sync Feb 15 20:06:24 volumio-primo sudo[12085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:06:24 volumio-primo sudo[12085]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:24 volumio-primo sudo[12081]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:06:24 volumio-primo sudo[12081]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:24 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:06:24 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:06:24 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:24 volumio-primo sudo[12081]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:24 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:06:24 volumio-primo sudo[12085]: pam_unix(sudo:session): session closed for user root Feb 15 20:06:24 volumio-primo volumio[11766]: info: Shairport-Sync Started Feb 15 20:06:24 volumio-primo volumio[11766]: info: [ASDebug] Togle GPIO: OFF Feb 15 20:06:24 volumio-primo volumio[11766]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:06:24 volumio-primo volumio[11766]: Error: EPERM: operation not permitted, write Feb 15 20:06:24 volumio-primo volumio[11766]: at Object.writeSync (fs.js:737:3) Feb 15 20:06:24 volumio-primo volumio[11766]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Feb 15 20:06:24 volumio-primo volumio[11766]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Feb 15 20:06:24 volumio-primo volumio[11766]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Feb 15 20:06:24 volumio-primo volumio[11766]: at listOnTimeout (internal/timers.js:557:17) Feb 15 20:06:24 volumio-primo volumio[11766]: at processTimers (internal/timers.js:500:7) { Feb 15 20:06:24 volumio-primo volumio[11766]: errno: -1, Feb 15 20:06:24 volumio-primo volumio[11766]: syscall: 'write', Feb 15 20:06:24 volumio-primo volumio[11766]: code: 'EPERM' Feb 15 20:06:24 volumio-primo volumio[11766]: } Feb 15 20:06:24 volumio-primo volumio[11766]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:06:24 volumio-primo sudo[12115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 20:05 Feb 15 20:06:24 volumio-primo sudo[12115]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:06:24 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:24 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:06:24 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:06:24 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:11:38 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="932fc3546f7cc8faa2b4d349036752dd"