-- Logs begin at Thu 2024-05-09 01:31:39 KST, end at Thu 2024-05-09 01:34:52 KST. -- May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10650, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10657, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10664, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10671, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10678, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10685, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10692, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10699, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10706, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10713, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10720, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10727, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10734, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10741, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10748, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10755, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10762, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10769, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10776, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10783, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10790, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10797, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10804, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10811, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10818, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10825, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10832, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10839, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10846, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10853, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10860, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10867, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10874, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10881, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10888, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10895, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10902, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10909, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10916, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10923, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10930, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10937, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10944, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10951, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10958, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10965, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10972, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10979, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10986, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10993, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11000, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11007, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11014, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11021, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11028, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11035, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11042, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11049, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11056, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11063, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11070, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11077, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11084, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11091, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11098, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11105, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11112, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11119, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11126, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11133, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11140, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11147, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11154, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11161, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11168, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11175, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11182, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11189, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11196, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11203, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11210, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11217, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11224, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11231, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11238, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11245, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11252, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11259, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11266, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11273, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11280, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11287, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11294, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11301, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11308, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11315, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11322, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11329, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11336, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11343, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11350, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11357, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11364, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11371, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11378, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11385, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11392, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11399, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11406, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11413, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11420, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11427, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11434, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11441, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11448, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11455, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11462, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11469, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11476, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11483, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11490, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11497, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11504, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11511, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11518, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11525, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11532, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11539, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11546, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11553, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11560, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11567, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11574, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11581, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11588, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11595, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ May 09 01:33:02 volumio volumio[996]: info: PLUGIN onShutdown : networkfs May 09 01:33:02 volumio volumio[996]: info: PLUGIN onShutdown : audiophonicsonoff May 09 01:33:02 volumio volumio[996]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:33:02 volumio volumio[996]: TypeError: Cannot read property 'writeSync' of undefined May 09 01:33:02 volumio volumio[996]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) May 09 01:33:02 volumio volumio[996]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) May 09 01:33:02 volumio volumio[996]: at HashMap. (/volumio/app/pluginmanager.js:621:31) May 09 01:33:02 volumio volumio[996]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 09 01:33:02 volumio volumio[996]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 09 01:33:02 volumio volumio[996]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) May 09 01:33:02 volumio volumio[996]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1313:24) May 09 01:33:02 volumio volumio[996]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:831:33) May 09 01:33:02 volumio volumio[996]: at Socket.emit (events.js:315:20) May 09 01:33:02 volumio volumio[996]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 09 01:33:02 volumio volumio[996]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:33:02 volumio sudo[11611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 01:32 May 09 01:33:02 volumio sudo[11611]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:02 volumio sudo[11611]: pam_unix(sudo:session): session closed for user root May 09 01:33:04 volumio volumio-remote-updater[644]: [2024-05-09 01:33:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 09 01:33:04 volumio volumio-remote-updater[644]: [2024-05-09 01:33:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 09 01:33:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 01:33:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 01:33:04 volumio systemd[1]: Started dynamicswap service. May 09 01:33:04 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:33:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 01:33:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 09 01:33:04 volumio systemd[1]: Stopped Volumio Backend Module. May 09 01:33:04 volumio systemd[1]: Started Volumio Backend Module. May 09 01:33:04 volumio systemd[1]: Started dynamicswap service. May 09 01:33:04 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:33:05 volumio volumio[11625]: info: ------------------------------------------- May 09 01:33:05 volumio volumio[11625]: info: ----- Volumio3 ---- May 09 01:33:05 volumio volumio[11625]: info: ------------------------------------------- May 09 01:33:05 volumio volumio[11625]: info: ----- System startup ---- May 09 01:33:05 volumio volumio[11625]: info: ------------------------------------------- May 09 01:33:05 volumio volumio[11625]: info: MYVOLUMIO Environment detected May 09 01:33:05 volumio volumio[11625]: info: Plugin folders cleanup May 09 01:33:05 volumio volumio[11625]: info: Scanning into folder /volumio/app/plugins/ May 09 01:33:05 volumio volumio[11625]: info: Scanning category audio_interface May 09 01:33:05 volumio volumio[11625]: info: Scanning category miscellanea May 09 01:33:05 volumio volumio[11625]: info: Scanning category music_service May 09 01:33:05 volumio volumio[11625]: info: Scanning category plugins.json May 09 01:33:05 volumio volumio[11625]: info: Scanning category system_controller May 09 01:33:05 volumio volumio[11625]: info: Scanning category user_interface May 09 01:33:05 volumio volumio[11625]: info: Scanning into folder /data/plugins/ May 09 01:33:05 volumio volumio[11625]: info: Scanning category audio_interface May 09 01:33:05 volumio volumio[11625]: info: Scanning category music_service May 09 01:33:05 volumio volumio[11625]: info: Scanning category system_controller May 09 01:33:05 volumio volumio[11625]: info: Scanning category user_interface May 09 01:33:05 volumio volumio[11625]: info: Plugin folders cleanup completed May 09 01:33:05 volumio volumio[11625]: info: ------------------------------------------- May 09 01:33:05 volumio volumio[11625]: info: ----- Core plugins startup ---- May 09 01:33:05 volumio volumio[11625]: info: ------------------------------------------- May 09 01:33:05 volumio volumio[11625]: info: Loading plugins from folder /volumio/app/plugins/ May 09 01:33:05 volumio volumio[11625]: info: Adding plugin upnp to MyMusic Plugins May 09 01:33:05 volumio volumio[11625]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 01:33:05 volumio volumio[11625]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 01:33:05 volumio volumio[11625]: info: Loading plugins from folder /data/plugins/ May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "system"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "appearance"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "network"... May 09 01:33:05 volumio volumio[11625]: info: Refreshing Cached IP Addresses May 09 01:33:05 volumio sudo[11652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:33:05 volumio sudo[11652]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:05 volumio sudo[11652]: pam_unix(sudo:session): session closed for user root May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "services"... May 09 01:33:05 volumio sudo[11654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "alsa_controller"... May 09 01:33:05 volumio sudo[11654]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:05 volumio sudo[11654]: pam_unix(sudo:session): session closed for user root May 09 01:33:05 volumio sudo[11657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 01:33:05 volumio sudo[11657]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:05 volumio volumio[11625]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. May 09 01:33:05 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "wizard"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "networkfs"... May 09 01:33:05 volumio volumio[11625]: info: Starting Udev Watcher for removable devices May 09 01:33:05 volumio volumio[11625]: info: Ignoring mount for partition: bootfs May 09 01:33:05 volumio volumio[11625]: info: Ignoring mount for partition: rootfs May 09 01:33:05 volumio volumio[11625]: info: Ignoring mount for partition: boot May 09 01:33:05 volumio volumio[11625]: info: Ignoring mount for partition: volumio May 09 01:33:05 volumio volumio[11625]: info: Ignoring mount for partition: volumio_data May 09 01:33:05 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "volumio_command_line_client"... May 09 01:33:05 volumio volumio[11625]: info: Plugin upnp is not enabled May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "my_music"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "mpd"... May 09 01:33:05 volumio volumio[11625]: info: Plugin upnp_browser is not enabled May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "alarm-clock"... May 09 01:33:05 volumio volumio[11625]: info: Plugin airplay_emulation is not enabled May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "last_100"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "webradio"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "i2s_dacs"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "volumiodiscovery"... May 09 01:33:05 volumio volumio[11625]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:33:05 volumio node[11625]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:33:05 volumio node[11625]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:05 volumio volumio[11625]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:05 volumio volumio[11625]: *** WARNING *** For more information see May 09 01:33:05 volumio volumio[11625]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:33:05 volumio volumio[11625]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:05 volumio volumio[11625]: *** WARNING *** For more information see May 09 01:33:05 volumio node[11625]: *** WARNING *** For more information see May 09 01:33:05 volumio node[11625]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:33:05 volumio node[11625]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:05 volumio node[11625]: *** WARNING *** For more information see May 09 01:33:05 volumio volumio[11625]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 01:33:05 volumio volumio[11625]: info: Discovery: Started advertising with name: Volumio May 09 01:33:05 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "autostart"... May 09 01:33:05 volumio volumio[11625]: info: Applying required configuration parameters for plugin autostart May 09 01:33:05 volumio volumio[11625]: info: AutoStart - onVolumioStart - read config.json May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "outputs"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "albumart"... May 09 01:33:05 volumio volumio[11625]: info: Plugin example_plugin is not enabled May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "inputs"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "updater_comm"... May 09 01:33:05 volumio volumio[11625]: info: Plugin mpdemulation is not enabled May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "rest_api"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "websocket"... May 09 01:33:05 volumio volumio[11625]: info: Loading plugin "fusiondsp"... May 09 01:33:06 volumio volumio[11625]: Forking 3 albumart workers May 09 01:33:06 volumio volumio[11625]: info: Applying required configuration parameters for plugin fusiondsp May 09 01:33:06 volumio volumio[11625]: info: Loading plugin "personal_radio"... May 09 01:33:06 volumio volumio[11625]: info: ControllerPersonalRadio::constructor May 09 01:33:06 volumio volumio[11625]: info: Loading plugin "audiophonicsonoff"... May 09 01:33:06 volumio volumio[11625]: Starting albumart workers May 09 01:33:06 volumio volumio[11625]: Starting albumart workers May 09 01:33:06 volumio volumio[11625]: info: Applying required configuration parameters for plugin audiophonicsonoff May 09 01:33:06 volumio volumio[11625]: info: Audiophonics on/off initiated May 09 01:33:06 volumio volumio[11625]: info: Loading plugin "backup_restore"... May 09 01:33:06 volumio volumio[11625]: Starting albumart workers May 09 01:33:06 volumio volumio[11625]: info: Applying required configuration parameters for plugin backup_restore May 09 01:33:06 volumio volumio[11625]: info: Loading plugin "Systeminfo"... May 09 01:33:06 volumio volumio[11625]: info: Loading i18n strings for locale ko May 09 01:33:06 volumio volumio[11625]: Updating browse sources language May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::initPlayerControls May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:33:06 volumio volumio[11625]: Express server listening on port 3000 May 09 01:33:06 volumio volumio[11625]: [Metrics] WebUI: 1s 323.82ms May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::resetVolumioState May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::getcurrentVolume May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:33:06 volumio volumio[11625]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:33:06 volumio volumio[11625]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.99 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/124.0.6367.111 Mobile/15E148 Safari/604.1 Total Clients: 1 May 09 01:33:06 volumio volumio[11625]: info: VolumeController:: Volume=undefined Mute =false May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::pushState May 09 01:33:06 volumio volumio[11625]: info: CorePlayQueue::getTrack 0 May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::volumioPushState May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::updateTrackBlock May 09 01:33:06 volumio volumio[11625]: info: CorePlayQueue::getTrackBlock May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:33:06 volumio volumio[11625]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::setRepeat true single undefined May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::pushState May 09 01:33:06 volumio volumio[11625]: info: CorePlayQueue::getTrack 0 May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::volumioPushState May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::setRandom true May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::pushState May 09 01:33:06 volumio volumio[11625]: info: CorePlayQueue::getTrack 0 May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::volumioPushState May 09 01:33:06 volumio volumio[11625]: info: Setting Device type: Raspberry PI May 09 01:33:06 volumio volumio[11625]: info: VolumeController:: Volume=undefined Mute =false May 09 01:33:06 volumio volumio[11625]: info: CoreStateMachine::pushState May 09 01:33:06 volumio volumio[11625]: info: CorePlayQueue::getTrack 0 May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::volumioPushState May 09 01:33:06 volumio volumio[11625]: info: Discovery: adding 1f8966a6-04ba-47db-aeca-a33edc5b5928 May 09 01:33:06 volumio volumio[11625]: info: Discovery: Found device Volumio May 09 01:33:06 volumio volumio[11625]: info: CoreCommandRouter::volumioGetState May 09 01:33:06 volumio volumio[11625]: info: CorePlayQueue::getTrack 0 May 09 01:33:08 volumio volumio[11625]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.99 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/124.0.6367.111 Mobile/15E148 Safari/604.1 Total Clients: 2 May 09 01:33:08 volumio volumio[11625]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:33:08 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:33:08 volumio volumio[11625]: info: CoreCommandRouter::volumioGetState May 09 01:33:08 volumio volumio[11625]: info: CorePlayQueue::getTrack 0 May 09 01:33:08 volumio volumio[11625]: info: CoreCommandRouter::volumioGetQueue May 09 01:33:08 volumio volumio[11625]: info: CoreStateMachine::getQueue May 09 01:33:08 volumio volumio[11625]: info: CorePlayQueue::getQueue May 09 01:33:08 volumio volumio[11625]: info: Listing playlists May 09 01:33:09 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:33:09 volumio volumio[11625]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 01:33:09 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 01:33:09 volumio volumio[11625]: info: Received Get System Version May 09 01:33:09 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:33:09 volumio volumio[11625]: info: Received Get System Info May 09 01:33:09 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 01:33:09 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 01:33:09 volumio volumio[11625]: info: Discovery: Getting this device information May 09 01:33:09 volumio volumio[11625]: info: CoreCommandRouter::volumioGetState May 09 01:33:09 volumio volumio[11625]: info: CorePlayQueue::getTrack 0 May 09 01:33:09 volumio volumio[11625]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 01:33:09 volumio volumio-remote-updater[644]: [2024-05-09 01:33:09] [connect] Successful connection May 09 01:33:09 volumio volumio-remote-updater[644]: [2024-05-09 01:33:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715185989 101 May 09 01:33:09 volumio volumio[11625]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 May 09 01:33:09 volumio sudo[11657]: pam_unix(sudo:session): session closed for user root May 09 01:33:19 volumio volumio[11625]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 09 01:33:19 volumio volumio[11625]: info: PLUGIN onReboot : networkfs May 09 01:33:19 volumio volumio[11625]: info: PLUGIN onReboot : audiophonicsonoff May 09 01:33:19 volumio volumio[11625]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:33:19 volumio volumio[11625]: TypeError: Cannot read property 'writeSync' of undefined May 09 01:33:19 volumio volumio[11625]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) May 09 01:33:19 volumio volumio[11625]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 09 01:33:19 volumio volumio[11625]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 09 01:33:19 volumio volumio[11625]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 09 01:33:19 volumio volumio[11625]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 09 01:33:19 volumio volumio[11625]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 09 01:33:19 volumio volumio[11625]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22) May 09 01:33:19 volumio volumio[11625]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33) May 09 01:33:19 volumio volumio[11625]: at Socket.emit (events.js:315:20) May 09 01:33:19 volumio volumio[11625]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 09 01:33:19 volumio volumio[11625]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:33:19 volumio sudo[11747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 01:32 May 09 01:33:19 volumio sudo[11747]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:20 volumio sudo[11747]: pam_unix(sudo:session): session closed for user root May 09 01:33:20 volumio volumio-remote-updater[644]: [2024-05-09 01:33:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 09 01:33:20 volumio volumio-remote-updater[644]: [2024-05-09 01:33:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 09 01:33:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 01:33:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 01:33:20 volumio systemd[1]: Started dynamicswap service. May 09 01:33:20 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:33:20 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 01:33:20 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 09 01:33:20 volumio systemd[1]: Stopped Volumio Backend Module. May 09 01:33:20 volumio systemd[1]: Started Volumio Backend Module. May 09 01:33:20 volumio systemd[1]: Started dynamicswap service. May 09 01:33:20 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:33:20 volumio volumio[11757]: info: ------------------------------------------- May 09 01:33:20 volumio volumio[11757]: info: ----- Volumio3 ---- May 09 01:33:20 volumio volumio[11757]: info: ------------------------------------------- May 09 01:33:20 volumio volumio[11757]: info: ----- System startup ---- May 09 01:33:20 volumio volumio[11757]: info: ------------------------------------------- May 09 01:33:20 volumio volumio[11757]: info: MYVOLUMIO Environment detected May 09 01:33:20 volumio volumio[11757]: info: Plugin folders cleanup May 09 01:33:20 volumio volumio[11757]: info: Scanning into folder /volumio/app/plugins/ May 09 01:33:20 volumio volumio[11757]: info: Scanning category audio_interface May 09 01:33:20 volumio volumio[11757]: info: Scanning category miscellanea May 09 01:33:20 volumio volumio[11757]: info: Scanning category music_service May 09 01:33:20 volumio volumio[11757]: info: Scanning category plugins.json May 09 01:33:20 volumio volumio[11757]: info: Scanning category system_controller May 09 01:33:20 volumio volumio[11757]: info: Scanning category user_interface May 09 01:33:20 volumio volumio[11757]: info: Scanning into folder /data/plugins/ May 09 01:33:20 volumio volumio[11757]: info: Scanning category audio_interface May 09 01:33:20 volumio volumio[11757]: info: Scanning category music_service May 09 01:33:20 volumio volumio[11757]: info: Scanning category system_controller May 09 01:33:20 volumio volumio[11757]: info: Scanning category user_interface May 09 01:33:20 volumio volumio[11757]: info: Plugin folders cleanup completed May 09 01:33:20 volumio volumio[11757]: info: ------------------------------------------- May 09 01:33:20 volumio volumio[11757]: info: ----- Core plugins startup ---- May 09 01:33:20 volumio volumio[11757]: info: ------------------------------------------- May 09 01:33:20 volumio volumio[11757]: info: Loading plugins from folder /volumio/app/plugins/ May 09 01:33:20 volumio volumio[11757]: info: Adding plugin upnp to MyMusic Plugins May 09 01:33:20 volumio volumio[11757]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 01:33:20 volumio volumio[11757]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 01:33:20 volumio volumio[11757]: info: Loading plugins from folder /data/plugins/ May 09 01:33:20 volumio volumio[11757]: info: Loading plugin "system"... May 09 01:33:20 volumio volumio[11757]: info: Loading plugin "appearance"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "network"... May 09 01:33:21 volumio volumio[11757]: info: Refreshing Cached IP Addresses May 09 01:33:21 volumio sudo[11784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:33:21 volumio sudo[11784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:21 volumio sudo[11784]: pam_unix(sudo:session): session closed for user root May 09 01:33:21 volumio sudo[11786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:33:21 volumio sudo[11786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "services"... May 09 01:33:21 volumio sudo[11786]: pam_unix(sudo:session): session closed for user root May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "alsa_controller"... May 09 01:33:21 volumio sudo[11790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 01:33:21 volumio sudo[11790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:21 volumio volumio[11757]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "wizard"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "networkfs"... May 09 01:33:21 volumio volumio[11757]: info: Starting Udev Watcher for removable devices May 09 01:33:21 volumio volumio[11757]: info: Ignoring mount for partition: bootfs May 09 01:33:21 volumio volumio[11757]: info: Ignoring mount for partition: rootfs May 09 01:33:21 volumio volumio[11757]: info: Ignoring mount for partition: boot May 09 01:33:21 volumio volumio[11757]: info: Ignoring mount for partition: volumio May 09 01:33:21 volumio volumio[11757]: info: Ignoring mount for partition: volumio_data May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "volumio_command_line_client"... May 09 01:33:21 volumio volumio[11757]: info: Plugin upnp is not enabled May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "my_music"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "mpd"... May 09 01:33:21 volumio volumio[11757]: info: Plugin upnp_browser is not enabled May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "alarm-clock"... May 09 01:33:21 volumio volumio[11757]: info: Plugin airplay_emulation is not enabled May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "last_100"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "webradio"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "i2s_dacs"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "volumiodiscovery"... May 09 01:33:21 volumio volumio[11757]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:33:21 volumio node[11757]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:33:21 volumio volumio[11757]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:21 volumio node[11757]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:21 volumio volumio[11757]: *** WARNING *** For more information see May 09 01:33:21 volumio node[11757]: *** WARNING *** For more information see May 09 01:33:21 volumio volumio[11757]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:33:21 volumio node[11757]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:33:21 volumio volumio[11757]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:21 volumio node[11757]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:21 volumio volumio[11757]: *** WARNING *** For more information see May 09 01:33:21 volumio node[11757]: *** WARNING *** For more information see May 09 01:33:21 volumio volumio[11757]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 01:33:21 volumio volumio[11757]: info: Discovery: Started advertising with name: Volumio May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "autostart"... May 09 01:33:21 volumio volumio[11757]: info: Applying required configuration parameters for plugin autostart May 09 01:33:21 volumio volumio[11757]: info: AutoStart - onVolumioStart - read config.json May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "outputs"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "albumart"... May 09 01:33:21 volumio volumio[11757]: info: Plugin example_plugin is not enabled May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "inputs"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "updater_comm"... May 09 01:33:21 volumio volumio[11757]: info: Plugin mpdemulation is not enabled May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "rest_api"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "websocket"... May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "fusiondsp"... May 09 01:33:21 volumio volumio[11757]: Forking 3 albumart workers May 09 01:33:21 volumio volumio[11757]: info: Applying required configuration parameters for plugin fusiondsp May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "personal_radio"... May 09 01:33:21 volumio volumio[11757]: info: ControllerPersonalRadio::constructor May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "audiophonicsonoff"... May 09 01:33:21 volumio volumio[11757]: Starting albumart workers May 09 01:33:21 volumio volumio[11757]: Starting albumart workers May 09 01:33:21 volumio volumio[11757]: Starting albumart workers May 09 01:33:21 volumio volumio[11757]: info: Applying required configuration parameters for plugin audiophonicsonoff May 09 01:33:21 volumio volumio[11757]: info: Audiophonics on/off initiated May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "backup_restore"... May 09 01:33:21 volumio volumio[11757]: info: Applying required configuration parameters for plugin backup_restore May 09 01:33:21 volumio volumio[11757]: info: Loading plugin "Systeminfo"... May 09 01:33:21 volumio volumio[11757]: info: Loading i18n strings for locale ko May 09 01:33:21 volumio volumio[11757]: Updating browse sources language May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::initPlayerControls May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:33:21 volumio volumio[11757]: Express server listening on port 3000 May 09 01:33:21 volumio volumio[11757]: [Metrics] WebUI: 1s 321.49ms May 09 01:33:21 volumio volumio[11757]: info: CoreStateMachine::resetVolumioState May 09 01:33:21 volumio volumio[11757]: info: CoreStateMachine::getcurrentVolume May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:33:21 volumio volumio[11757]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:33:21 volumio volumio[11757]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.99 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/124.0.6367.111 Mobile/15E148 Safari/604.1 Total Clients: 1 May 09 01:33:21 volumio volumio[11757]: info: VolumeController:: Volume=undefined Mute =false May 09 01:33:21 volumio volumio[11757]: info: CoreStateMachine::pushState May 09 01:33:21 volumio volumio[11757]: info: CorePlayQueue::getTrack 0 May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::volumioPushState May 09 01:33:21 volumio volumio[11757]: info: CoreStateMachine::updateTrackBlock May 09 01:33:21 volumio volumio[11757]: info: CorePlayQueue::getTrackBlock May 09 01:33:21 volumio volumio[11757]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:33:22 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:33:22 volumio volumio[11757]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:33:22 volumio volumio[11757]: info: CoreStateMachine::setRepeat true single undefined May 09 01:33:22 volumio volumio[11757]: info: CoreStateMachine::pushState May 09 01:33:22 volumio volumio[11757]: info: CorePlayQueue::getTrack 0 May 09 01:33:22 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:22 volumio volumio[11757]: info: CoreCommandRouter::volumioPushState May 09 01:33:22 volumio volumio[11757]: info: CoreStateMachine::setRandom true May 09 01:33:22 volumio volumio[11757]: info: CoreStateMachine::pushState May 09 01:33:22 volumio volumio[11757]: info: CorePlayQueue::getTrack 0 May 09 01:33:22 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:22 volumio volumio[11757]: info: CoreCommandRouter::volumioPushState May 09 01:33:22 volumio volumio[11757]: info: Setting Device type: Raspberry PI May 09 01:33:22 volumio volumio[11757]: info: VolumeController:: Volume=undefined Mute =false May 09 01:33:22 volumio volumio[11757]: info: CoreStateMachine::pushState May 09 01:33:22 volumio volumio[11757]: info: CorePlayQueue::getTrack 0 May 09 01:33:22 volumio volumio[11757]: info: CoreCommandRouter::volumioPushState May 09 01:33:22 volumio volumio[11757]: info: Discovery: adding 1f8966a6-04ba-47db-aeca-a33edc5b5928 May 09 01:33:22 volumio volumio[11757]: info: Discovery: Found device Volumio May 09 01:33:22 volumio volumio[11757]: info: CoreCommandRouter::volumioGetState May 09 01:33:22 volumio volumio[11757]: info: CorePlayQueue::getTrack 0 May 09 01:33:23 volumio volumio[11757]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.99 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/124.0.6367.111 Mobile/15E148 Safari/604.1 Total Clients: 2 May 09 01:33:23 volumio volumio[11757]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:33:23 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:33:23 volumio volumio[11757]: info: CoreCommandRouter::volumioGetState May 09 01:33:23 volumio volumio[11757]: info: CorePlayQueue::getTrack 0 May 09 01:33:23 volumio volumio[11757]: info: CoreCommandRouter::volumioGetQueue May 09 01:33:23 volumio volumio[11757]: info: CoreStateMachine::getQueue May 09 01:33:23 volumio volumio[11757]: info: CorePlayQueue::getQueue May 09 01:33:23 volumio volumio[11757]: info: Listing playlists May 09 01:33:25 volumio volumio-remote-updater[644]: [2024-05-09 01:33:25] [connect] Successful connection May 09 01:33:25 volumio volumio-remote-updater[644]: [2024-05-09 01:33:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715186005 101 May 09 01:33:25 volumio volumio[11757]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 May 09 01:33:25 volumio sudo[11790]: pam_unix(sudo:session): session closed for user root May 09 01:33:45 volumio sudo[11909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:33:45 volumio sudo[11909]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:45 volumio sudo[11909]: pam_unix(sudo:session): session closed for user root May 09 01:33:45 volumio sudo[11911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:33:45 volumio sudo[11911]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:45 volumio sudo[11911]: pam_unix(sudo:session): session closed for user root May 09 01:33:45 volumio volumio[11757]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 4 May 09 01:33:45 volumio volumio[11757]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:33:45 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:33:45 volumio volumio[11757]: info: CoreCommandRouter::volumioGetState May 09 01:33:45 volumio volumio[11757]: info: CorePlayQueue::getTrack 0 May 09 01:33:45 volumio volumio[11757]: info: CoreCommandRouter::volumioGetQueue May 09 01:33:45 volumio volumio[11757]: info: CoreStateMachine::getQueue May 09 01:33:45 volumio volumio[11757]: info: CorePlayQueue::getQueue May 09 01:33:45 volumio volumio[11757]: info: Listing playlists May 09 01:33:45 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 09 01:33:45 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 09 01:33:45 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 09 01:33:45 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:33:47 volumio volumio[11757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 01:33:51 volumio volumio[11757]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ May 09 01:33:51 volumio volumio[11757]: info: PLUGIN onShutdown : networkfs May 09 01:33:51 volumio volumio[11757]: info: PLUGIN onShutdown : audiophonicsonoff May 09 01:33:51 volumio volumio[11757]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:33:51 volumio volumio[11757]: TypeError: Cannot read property 'writeSync' of undefined May 09 01:33:51 volumio volumio[11757]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) May 09 01:33:51 volumio volumio[11757]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) May 09 01:33:51 volumio volumio[11757]: at HashMap. (/volumio/app/pluginmanager.js:621:31) May 09 01:33:51 volumio volumio[11757]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 09 01:33:51 volumio volumio[11757]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 09 01:33:51 volumio volumio[11757]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) May 09 01:33:51 volumio volumio[11757]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1313:24) May 09 01:33:51 volumio volumio[11757]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:831:33) May 09 01:33:51 volumio volumio[11757]: at Socket.emit (events.js:315:20) May 09 01:33:51 volumio volumio[11757]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 09 01:33:51 volumio volumio[11757]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:33:51 volumio sudo[11923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 01:32 May 09 01:33:51 volumio sudo[11923]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:51 volumio sudo[11923]: pam_unix(sudo:session): session closed for user root May 09 01:33:51 volumio volumio-remote-updater[644]: [2024-05-09 01:33:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 09 01:33:51 volumio volumio-remote-updater[644]: [2024-05-09 01:33:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 09 01:33:51 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 01:33:51 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 01:33:51 volumio systemd[1]: Started dynamicswap service. May 09 01:33:51 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:33:51 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 01:33:51 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. May 09 01:33:51 volumio systemd[1]: Stopped Volumio Backend Module. May 09 01:33:51 volumio systemd[1]: Started Volumio Backend Module. May 09 01:33:51 volumio systemd[1]: Started dynamicswap service. May 09 01:33:51 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:33:51 volumio volumio[11933]: info: ------------------------------------------- May 09 01:33:51 volumio volumio[11933]: info: ----- Volumio3 ---- May 09 01:33:51 volumio volumio[11933]: info: ------------------------------------------- May 09 01:33:51 volumio volumio[11933]: info: ----- System startup ---- May 09 01:33:51 volumio volumio[11933]: info: ------------------------------------------- May 09 01:33:51 volumio volumio[11933]: info: MYVOLUMIO Environment detected May 09 01:33:51 volumio volumio[11933]: info: Plugin folders cleanup May 09 01:33:51 volumio volumio[11933]: info: Scanning into folder /volumio/app/plugins/ May 09 01:33:51 volumio volumio[11933]: info: Scanning category audio_interface May 09 01:33:51 volumio volumio[11933]: info: Scanning category miscellanea May 09 01:33:51 volumio volumio[11933]: info: Scanning category music_service May 09 01:33:51 volumio volumio[11933]: info: Scanning category plugins.json May 09 01:33:51 volumio volumio[11933]: info: Scanning category system_controller May 09 01:33:51 volumio volumio[11933]: info: Scanning category user_interface May 09 01:33:51 volumio volumio[11933]: info: Scanning into folder /data/plugins/ May 09 01:33:51 volumio volumio[11933]: info: Scanning category audio_interface May 09 01:33:51 volumio volumio[11933]: info: Scanning category music_service May 09 01:33:51 volumio volumio[11933]: info: Scanning category system_controller May 09 01:33:51 volumio volumio[11933]: info: Scanning category user_interface May 09 01:33:51 volumio volumio[11933]: info: Plugin folders cleanup completed May 09 01:33:51 volumio volumio[11933]: info: ------------------------------------------- May 09 01:33:51 volumio volumio[11933]: info: ----- Core plugins startup ---- May 09 01:33:51 volumio volumio[11933]: info: ------------------------------------------- May 09 01:33:51 volumio volumio[11933]: info: Loading plugins from folder /volumio/app/plugins/ May 09 01:33:51 volumio volumio[11933]: info: Adding plugin upnp to MyMusic Plugins May 09 01:33:51 volumio volumio[11933]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 01:33:51 volumio volumio[11933]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 01:33:51 volumio volumio[11933]: info: Loading plugins from folder /data/plugins/ May 09 01:33:51 volumio volumio[11933]: info: Loading plugin "system"... May 09 01:33:51 volumio volumio[11933]: info: Loading plugin "appearance"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "network"... May 09 01:33:52 volumio volumio[11933]: info: Refreshing Cached IP Addresses May 09 01:33:52 volumio sudo[11960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:33:52 volumio sudo[11960]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:52 volumio sudo[11960]: pam_unix(sudo:session): session closed for user root May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "services"... May 09 01:33:52 volumio sudo[11962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:33:52 volumio sudo[11962]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "alsa_controller"... May 09 01:33:52 volumio sudo[11962]: pam_unix(sudo:session): session closed for user root May 09 01:33:52 volumio sudo[11965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 01:33:52 volumio sudo[11965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:33:52 volumio volumio[11933]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "wizard"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "networkfs"... May 09 01:33:52 volumio volumio[11933]: info: Starting Udev Watcher for removable devices May 09 01:33:52 volumio volumio[11933]: info: Ignoring mount for partition: bootfs May 09 01:33:52 volumio volumio[11933]: info: Ignoring mount for partition: rootfs May 09 01:33:52 volumio volumio[11933]: info: Ignoring mount for partition: boot May 09 01:33:52 volumio volumio[11933]: info: Ignoring mount for partition: volumio May 09 01:33:52 volumio volumio[11933]: info: Ignoring mount for partition: volumio_data May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "volumio_command_line_client"... May 09 01:33:52 volumio volumio[11933]: info: Plugin upnp is not enabled May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "my_music"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "mpd"... May 09 01:33:52 volumio volumio[11933]: info: Plugin upnp_browser is not enabled May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "alarm-clock"... May 09 01:33:52 volumio volumio[11933]: info: Plugin airplay_emulation is not enabled May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "last_100"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "webradio"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "i2s_dacs"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "volumiodiscovery"... May 09 01:33:52 volumio volumio[11933]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:33:52 volumio node[11933]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:33:52 volumio node[11933]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:52 volumio volumio[11933]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:52 volumio volumio[11933]: *** WARNING *** For more information see May 09 01:33:52 volumio volumio[11933]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:33:52 volumio volumio[11933]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:52 volumio volumio[11933]: *** WARNING *** For more information see May 09 01:33:52 volumio node[11933]: *** WARNING *** For more information see May 09 01:33:52 volumio node[11933]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:33:52 volumio node[11933]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:33:52 volumio node[11933]: *** WARNING *** For more information see May 09 01:33:52 volumio volumio[11933]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 01:33:52 volumio volumio[11933]: info: Discovery: Started advertising with name: Volumio May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "autostart"... May 09 01:33:52 volumio volumio[11933]: info: Applying required configuration parameters for plugin autostart May 09 01:33:52 volumio volumio[11933]: info: AutoStart - onVolumioStart - read config.json May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "outputs"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "albumart"... May 09 01:33:52 volumio volumio[11933]: info: Plugin example_plugin is not enabled May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "inputs"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "updater_comm"... May 09 01:33:52 volumio volumio[11933]: info: Plugin mpdemulation is not enabled May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "rest_api"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "websocket"... May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "fusiondsp"... May 09 01:33:52 volumio volumio[11933]: Forking 3 albumart workers May 09 01:33:52 volumio volumio[11933]: info: Applying required configuration parameters for plugin fusiondsp May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "personal_radio"... May 09 01:33:52 volumio volumio[11933]: info: ControllerPersonalRadio::constructor May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "audiophonicsonoff"... May 09 01:33:52 volumio volumio[11933]: Starting albumart workers May 09 01:33:52 volumio volumio[11933]: Starting albumart workers May 09 01:33:52 volumio volumio[11933]: info: Applying required configuration parameters for plugin audiophonicsonoff May 09 01:33:52 volumio volumio[11933]: info: Audiophonics on/off initiated May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "backup_restore"... May 09 01:33:52 volumio volumio[11933]: Starting albumart workers May 09 01:33:52 volumio volumio[11933]: info: Applying required configuration parameters for plugin backup_restore May 09 01:33:52 volumio volumio[11933]: info: Loading plugin "Systeminfo"... May 09 01:33:52 volumio volumio[11933]: info: Loading i18n strings for locale ko May 09 01:33:52 volumio volumio[11933]: Updating browse sources language May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::initPlayerControls May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:33:52 volumio volumio[11933]: Express server listening on port 3000 May 09 01:33:52 volumio volumio[11933]: [Metrics] WebUI: 1s 319.40ms May 09 01:33:52 volumio volumio[11933]: info: CoreStateMachine::resetVolumioState May 09 01:33:52 volumio volumio[11933]: info: CoreStateMachine::getcurrentVolume May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:33:52 volumio volumio[11933]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:33:52 volumio volumio[11933]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 1 May 09 01:33:52 volumio volumio[11933]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.99 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/124.0.6367.111 Mobile/15E148 Safari/604.1 Total Clients: 2 May 09 01:33:52 volumio volumio[11933]: info: VolumeController:: Volume=undefined Mute =false May 09 01:33:52 volumio volumio[11933]: info: CoreStateMachine::pushState May 09 01:33:52 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::volumioPushState May 09 01:33:52 volumio volumio[11933]: info: CoreStateMachine::updateTrackBlock May 09 01:33:52 volumio volumio[11933]: info: CorePlayQueue::getTrackBlock May 09 01:33:52 volumio volumio[11933]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:33:53 volumio volumio[11933]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:33:53 volumio volumio[11933]: info: CoreStateMachine::setRepeat true single undefined May 09 01:33:53 volumio volumio[11933]: info: CoreStateMachine::pushState May 09 01:33:53 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::volumioPushState May 09 01:33:53 volumio volumio[11933]: info: CoreStateMachine::setRandom true May 09 01:33:53 volumio volumio[11933]: info: CoreStateMachine::pushState May 09 01:33:53 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::volumioPushState May 09 01:33:53 volumio volumio[11933]: info: Setting Device type: Raspberry PI May 09 01:33:53 volumio volumio[11933]: info: VolumeController:: Volume=undefined Mute =false May 09 01:33:53 volumio volumio[11933]: info: CoreStateMachine::pushState May 09 01:33:53 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::volumioPushState May 09 01:33:53 volumio volumio[11933]: info: Discovery: adding 1f8966a6-04ba-47db-aeca-a33edc5b5928 May 09 01:33:53 volumio volumio[11933]: info: Discovery: Found device Volumio May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::volumioGetState May 09 01:33:53 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::volumioGetState May 09 01:33:53 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:33:53 volumio volumio[11933]: info: CoreCommandRouter::volumioGetQueue May 09 01:33:53 volumio volumio[11933]: info: CoreStateMachine::getQueue May 09 01:33:53 volumio volumio[11933]: info: CorePlayQueue::getQueue May 09 01:33:53 volumio volumio[11933]: info: Listing playlists May 09 01:33:54 volumio volumio[11933]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 3 May 09 01:33:54 volumio volumio[11933]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:33:54 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:33:54 volumio volumio[11933]: info: CoreCommandRouter::volumioGetState May 09 01:33:54 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:33:54 volumio volumio[11933]: info: CoreCommandRouter::volumioGetQueue May 09 01:33:54 volumio volumio[11933]: info: CoreStateMachine::getQueue May 09 01:33:54 volumio volumio[11933]: info: CorePlayQueue::getQueue May 09 01:33:54 volumio volumio[11933]: info: Listing playlists May 09 01:33:56 volumio sudo[11965]: pam_unix(sudo:session): session closed for user root May 09 01:33:56 volumio volumio-remote-updater[644]: [2024-05-09 01:33:56] [connect] Successful connection May 09 01:33:56 volumio volumio-remote-updater[644]: [2024-05-09 01:33:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715186036 101 May 09 01:33:56 volumio volumio[11933]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 May 09 01:34:04 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:34:04 volumio volumio[11933]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 01:34:04 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 01:34:04 volumio volumio[11933]: info: Received Get System Version May 09 01:34:04 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:34:04 volumio volumio[11933]: info: Received Get System Info May 09 01:34:04 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 01:34:04 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 01:34:04 volumio volumio[11933]: info: Discovery: Getting this device information May 09 01:34:04 volumio volumio[11933]: info: CoreCommandRouter::volumioGetState May 09 01:34:04 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:34:04 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 01:34:05 volumio sudo[12047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:34:05 volumio sudo[12047]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:05 volumio sudo[12047]: pam_unix(sudo:session): session closed for user root May 09 01:34:05 volumio sudo[12049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:34:05 volumio sudo[12049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:05 volumio sudo[12049]: pam_unix(sudo:session): session closed for user root May 09 01:34:05 volumio volumio[11933]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 3 May 09 01:34:06 volumio sudo[12053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:34:06 volumio sudo[12053]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:06 volumio sudo[12053]: pam_unix(sudo:session): session closed for user root May 09 01:34:06 volumio sudo[12055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:34:06 volumio sudo[12055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:06 volumio sudo[12055]: pam_unix(sudo:session): session closed for user root May 09 01:34:06 volumio volumio[11933]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 4 May 09 01:34:06 volumio volumio[11933]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:34:06 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:34:06 volumio volumio[11933]: info: CoreCommandRouter::volumioGetState May 09 01:34:06 volumio volumio[11933]: info: CorePlayQueue::getTrack 0 May 09 01:34:06 volumio volumio[11933]: info: CoreCommandRouter::volumioGetQueue May 09 01:34:06 volumio volumio[11933]: info: CoreStateMachine::getQueue May 09 01:34:06 volumio volumio[11933]: info: CorePlayQueue::getQueue May 09 01:34:06 volumio volumio[11933]: info: Listing playlists May 09 01:34:06 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 09 01:34:06 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 09 01:34:06 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 09 01:34:06 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:34:08 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::volumioGetBrowseSources May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::volumioGetBrowseSources May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::volumioGetBrowseSources May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 01:34:15 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 09 01:34:17 volumio volumio[11933]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 09 01:34:17 volumio volumio[11933]: verbose: ControllerMpd::sendMpdCommand update May 09 01:34:17 volumio volumio[11933]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:34:17 volumio volumio[11933]: TypeError: Cannot read property 'then' of undefined May 09 01:34:17 volumio volumio[11933]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) May 09 01:34:17 volumio volumio[11933]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) May 09 01:34:17 volumio volumio[11933]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) May 09 01:34:17 volumio volumio[11933]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26) May 09 01:34:17 volumio volumio[11933]: at Socket.emit (events.js:315:20) May 09 01:34:17 volumio volumio[11933]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 09 01:34:17 volumio volumio[11933]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 09 01:34:17 volumio volumio[11933]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:34:17 volumio sudo[12067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 01:33 May 09 01:34:17 volumio sudo[12067]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:17 volumio sudo[12067]: pam_unix(sudo:session): session closed for user root May 09 01:34:18 volumio volumio-remote-updater[644]: [2024-05-09 01:34:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 09 01:34:18 volumio volumio-remote-updater[644]: [2024-05-09 01:34:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 09 01:34:18 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 01:34:18 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 01:34:18 volumio systemd[1]: Started dynamicswap service. May 09 01:34:18 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:34:18 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 01:34:18 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. May 09 01:34:18 volumio systemd[1]: Stopped Volumio Backend Module. May 09 01:34:18 volumio systemd[1]: Started Volumio Backend Module. May 09 01:34:18 volumio systemd[1]: Started dynamicswap service. May 09 01:34:18 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:34:19 volumio volumio[12080]: info: ------------------------------------------- May 09 01:34:19 volumio volumio[12080]: info: ----- Volumio3 ---- May 09 01:34:19 volumio volumio[12080]: info: ------------------------------------------- May 09 01:34:19 volumio volumio[12080]: info: ----- System startup ---- May 09 01:34:19 volumio volumio[12080]: info: ------------------------------------------- May 09 01:34:19 volumio volumio[12080]: info: MYVOLUMIO Environment detected May 09 01:34:19 volumio volumio[12080]: info: Plugin folders cleanup May 09 01:34:19 volumio volumio[12080]: info: Scanning into folder /volumio/app/plugins/ May 09 01:34:19 volumio volumio[12080]: info: Scanning category audio_interface May 09 01:34:19 volumio volumio[12080]: info: Scanning category miscellanea May 09 01:34:19 volumio volumio[12080]: info: Scanning category music_service May 09 01:34:19 volumio volumio[12080]: info: Scanning category plugins.json May 09 01:34:19 volumio volumio[12080]: info: Scanning category system_controller May 09 01:34:19 volumio volumio[12080]: info: Scanning category user_interface May 09 01:34:19 volumio volumio[12080]: info: Scanning into folder /data/plugins/ May 09 01:34:19 volumio volumio[12080]: info: Scanning category audio_interface May 09 01:34:19 volumio volumio[12080]: info: Scanning category music_service May 09 01:34:19 volumio volumio[12080]: info: Scanning category system_controller May 09 01:34:19 volumio volumio[12080]: info: Scanning category user_interface May 09 01:34:19 volumio volumio[12080]: info: Plugin folders cleanup completed May 09 01:34:19 volumio volumio[12080]: info: ------------------------------------------- May 09 01:34:19 volumio volumio[12080]: info: ----- Core plugins startup ---- May 09 01:34:19 volumio volumio[12080]: info: ------------------------------------------- May 09 01:34:19 volumio volumio[12080]: info: Loading plugins from folder /volumio/app/plugins/ May 09 01:34:19 volumio volumio[12080]: info: Adding plugin upnp to MyMusic Plugins May 09 01:34:19 volumio volumio[12080]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 01:34:19 volumio volumio[12080]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 01:34:19 volumio volumio[12080]: info: Loading plugins from folder /data/plugins/ May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "system"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "appearance"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "network"... May 09 01:34:19 volumio volumio[12080]: info: Refreshing Cached IP Addresses May 09 01:34:19 volumio sudo[12107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:34:19 volumio sudo[12107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:19 volumio sudo[12107]: pam_unix(sudo:session): session closed for user root May 09 01:34:19 volumio sudo[12109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:34:19 volumio sudo[12109]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "services"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "alsa_controller"... May 09 01:34:19 volumio sudo[12109]: pam_unix(sudo:session): session closed for user root May 09 01:34:19 volumio sudo[12112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 01:34:19 volumio sudo[12112]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:19 volumio volumio[12080]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. May 09 01:34:19 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "wizard"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "networkfs"... May 09 01:34:19 volumio volumio[12080]: info: Starting Udev Watcher for removable devices May 09 01:34:19 volumio volumio[12080]: info: Ignoring mount for partition: bootfs May 09 01:34:19 volumio volumio[12080]: info: Ignoring mount for partition: rootfs May 09 01:34:19 volumio volumio[12080]: info: Ignoring mount for partition: boot May 09 01:34:19 volumio volumio[12080]: info: Ignoring mount for partition: volumio May 09 01:34:19 volumio volumio[12080]: info: Ignoring mount for partition: volumio_data May 09 01:34:19 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "volumio_command_line_client"... May 09 01:34:19 volumio volumio[12080]: info: Plugin upnp is not enabled May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "my_music"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "mpd"... May 09 01:34:19 volumio volumio[12080]: info: Plugin upnp_browser is not enabled May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "alarm-clock"... May 09 01:34:19 volumio volumio[12080]: info: Plugin airplay_emulation is not enabled May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "last_100"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "webradio"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "i2s_dacs"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "volumiodiscovery"... May 09 01:34:19 volumio volumio[12080]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:34:19 volumio node[12080]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:34:19 volumio node[12080]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:34:19 volumio volumio[12080]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:34:19 volumio volumio[12080]: *** WARNING *** For more information see May 09 01:34:19 volumio volumio[12080]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:34:19 volumio volumio[12080]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:34:19 volumio volumio[12080]: *** WARNING *** For more information see May 09 01:34:19 volumio node[12080]: *** WARNING *** For more information see May 09 01:34:19 volumio node[12080]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:34:19 volumio node[12080]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:34:19 volumio node[12080]: *** WARNING *** For more information see May 09 01:34:19 volumio volumio[12080]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 01:34:19 volumio volumio[12080]: info: Discovery: Started advertising with name: Volumio May 09 01:34:19 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "autostart"... May 09 01:34:19 volumio volumio[12080]: info: Applying required configuration parameters for plugin autostart May 09 01:34:19 volumio volumio[12080]: info: AutoStart - onVolumioStart - read config.json May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "outputs"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "albumart"... May 09 01:34:19 volumio volumio[12080]: info: Plugin example_plugin is not enabled May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "inputs"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "updater_comm"... May 09 01:34:19 volumio volumio[12080]: info: Plugin mpdemulation is not enabled May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "rest_api"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "websocket"... May 09 01:34:19 volumio volumio[12080]: info: Loading plugin "fusiondsp"... May 09 01:34:20 volumio volumio[12080]: Forking 3 albumart workers May 09 01:34:20 volumio volumio[12080]: info: Applying required configuration parameters for plugin fusiondsp May 09 01:34:20 volumio volumio[12080]: info: Loading plugin "personal_radio"... May 09 01:34:20 volumio volumio[12080]: info: ControllerPersonalRadio::constructor May 09 01:34:20 volumio volumio[12080]: info: Loading plugin "audiophonicsonoff"... May 09 01:34:20 volumio volumio[12080]: Starting albumart workers May 09 01:34:20 volumio volumio[12080]: info: Applying required configuration parameters for plugin audiophonicsonoff May 09 01:34:20 volumio volumio[12080]: info: Audiophonics on/off initiated May 09 01:34:20 volumio volumio[12080]: info: Loading plugin "backup_restore"... May 09 01:34:20 volumio volumio[12080]: Starting albumart workers May 09 01:34:20 volumio volumio[12080]: Starting albumart workers May 09 01:34:20 volumio volumio[12080]: info: Applying required configuration parameters for plugin backup_restore May 09 01:34:20 volumio volumio[12080]: info: Loading plugin "Systeminfo"... May 09 01:34:20 volumio volumio[12080]: info: Loading i18n strings for locale ko May 09 01:34:20 volumio volumio[12080]: Updating browse sources language May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::initPlayerControls May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:34:20 volumio volumio[12080]: Express server listening on port 3000 May 09 01:34:20 volumio volumio[12080]: [Metrics] WebUI: 1s 307.10ms May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::resetVolumioState May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::getcurrentVolume May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:34:20 volumio volumio[12080]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:34:20 volumio volumio[12080]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 1 May 09 01:34:20 volumio volumio[12080]: info: VolumeController:: Volume=undefined Mute =false May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::pushState May 09 01:34:20 volumio volumio[12080]: info: CorePlayQueue::getTrack 0 May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::volumioPushState May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::updateTrackBlock May 09 01:34:20 volumio volumio[12080]: info: CorePlayQueue::getTrackBlock May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:34:20 volumio volumio[12080]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::setRepeat true single undefined May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::pushState May 09 01:34:20 volumio volumio[12080]: info: CorePlayQueue::getTrack 0 May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::volumioPushState May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::setRandom true May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::pushState May 09 01:34:20 volumio volumio[12080]: info: CorePlayQueue::getTrack 0 May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::volumioPushState May 09 01:34:20 volumio volumio[12080]: info: Setting Device type: Raspberry PI May 09 01:34:20 volumio volumio[12080]: info: VolumeController:: Volume=undefined Mute =false May 09 01:34:20 volumio volumio[12080]: info: CoreStateMachine::pushState May 09 01:34:20 volumio volumio[12080]: info: CorePlayQueue::getTrack 0 May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::volumioPushState May 09 01:34:20 volumio volumio[12080]: info: Discovery: adding 1f8966a6-04ba-47db-aeca-a33edc5b5928 May 09 01:34:20 volumio volumio[12080]: info: Discovery: Found device Volumio May 09 01:34:20 volumio volumio[12080]: info: CoreCommandRouter::volumioGetState May 09 01:34:20 volumio volumio[12080]: info: CorePlayQueue::getTrack 0 May 09 01:34:21 volumio volumio[12080]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 2 May 09 01:34:21 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 01:34:21 volumio volumio[12080]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:34:21 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:34:21 volumio volumio[12080]: info: CoreCommandRouter::volumioGetState May 09 01:34:21 volumio volumio[12080]: info: CorePlayQueue::getTrack 0 May 09 01:34:21 volumio volumio[12080]: info: CoreCommandRouter::volumioGetQueue May 09 01:34:21 volumio volumio[12080]: info: CoreStateMachine::getQueue May 09 01:34:21 volumio volumio[12080]: info: CorePlayQueue::getQueue May 09 01:34:21 volumio volumio[12080]: info: Listing playlists May 09 01:34:23 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 01:34:23 volumio volumio-remote-updater[644]: [2024-05-09 01:34:23] [connect] Successful connection May 09 01:34:23 volumio volumio-remote-updater[644]: [2024-05-09 01:34:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715186063 101 May 09 01:34:23 volumio volumio[12080]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 May 09 01:34:23 volumio sudo[12112]: pam_unix(sudo:session): session closed for user root May 09 01:34:23 volumio volumio[12080]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 09 01:34:23 volumio volumio[12080]: verbose: ControllerMpd::sendMpdCommand rescan May 09 01:34:23 volumio volumio[12080]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:34:23 volumio volumio[12080]: TypeError: Cannot read property 'then' of undefined May 09 01:34:23 volumio volumio[12080]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) May 09 01:34:23 volumio volumio[12080]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1885:15) May 09 01:34:23 volumio volumio[12080]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) May 09 01:34:23 volumio volumio[12080]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1045:26) May 09 01:34:23 volumio volumio[12080]: at Socket.emit (events.js:315:20) May 09 01:34:23 volumio volumio[12080]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 09 01:34:23 volumio volumio[12080]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 09 01:34:23 volumio volumio[12080]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:34:23 volumio sudo[12202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 01:33 May 09 01:34:23 volumio sudo[12202]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:23 volumio sudo[12202]: pam_unix(sudo:session): session closed for user root May 09 01:34:23 volumio volumio-remote-updater[644]: [2024-05-09 01:34:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 09 01:34:23 volumio volumio-remote-updater[644]: [2024-05-09 01:34:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 09 01:34:23 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 01:34:23 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 01:34:23 volumio systemd[1]: Started dynamicswap service. May 09 01:34:23 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:34:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 01:34:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. May 09 01:34:24 volumio systemd[1]: Stopped Volumio Backend Module. May 09 01:34:24 volumio systemd[1]: Started Volumio Backend Module. May 09 01:34:24 volumio systemd[1]: Started dynamicswap service. May 09 01:34:24 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 01:34:24 volumio volumio[12213]: info: ------------------------------------------- May 09 01:34:24 volumio volumio[12213]: info: ----- Volumio3 ---- May 09 01:34:24 volumio volumio[12213]: info: ------------------------------------------- May 09 01:34:24 volumio volumio[12213]: info: ----- System startup ---- May 09 01:34:24 volumio volumio[12213]: info: ------------------------------------------- May 09 01:34:24 volumio volumio[12213]: info: MYVOLUMIO Environment detected May 09 01:34:24 volumio volumio[12213]: info: Plugin folders cleanup May 09 01:34:24 volumio volumio[12213]: info: Scanning into folder /volumio/app/plugins/ May 09 01:34:24 volumio volumio[12213]: info: Scanning category audio_interface May 09 01:34:24 volumio volumio[12213]: info: Scanning category miscellanea May 09 01:34:24 volumio volumio[12213]: info: Scanning category music_service May 09 01:34:24 volumio volumio[12213]: info: Scanning category plugins.json May 09 01:34:24 volumio volumio[12213]: info: Scanning category system_controller May 09 01:34:24 volumio volumio[12213]: info: Scanning category user_interface May 09 01:34:24 volumio volumio[12213]: info: Scanning into folder /data/plugins/ May 09 01:34:24 volumio volumio[12213]: info: Scanning category audio_interface May 09 01:34:24 volumio volumio[12213]: info: Scanning category music_service May 09 01:34:24 volumio volumio[12213]: info: Scanning category system_controller May 09 01:34:24 volumio volumio[12213]: info: Scanning category user_interface May 09 01:34:24 volumio volumio[12213]: info: Plugin folders cleanup completed May 09 01:34:24 volumio volumio[12213]: info: ------------------------------------------- May 09 01:34:24 volumio volumio[12213]: info: ----- Core plugins startup ---- May 09 01:34:24 volumio volumio[12213]: info: ------------------------------------------- May 09 01:34:24 volumio volumio[12213]: info: Loading plugins from folder /volumio/app/plugins/ May 09 01:34:24 volumio volumio[12213]: info: Adding plugin upnp to MyMusic Plugins May 09 01:34:24 volumio volumio[12213]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 01:34:24 volumio volumio[12213]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 01:34:24 volumio volumio[12213]: info: Loading plugins from folder /data/plugins/ May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "system"... May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "appearance"... May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "network"... May 09 01:34:24 volumio volumio[12213]: info: Refreshing Cached IP Addresses May 09 01:34:24 volumio sudo[12240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:34:24 volumio sudo[12240]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:24 volumio sudo[12240]: pam_unix(sudo:session): session closed for user root May 09 01:34:24 volumio sudo[12242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:34:24 volumio sudo[12242]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "services"... May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "alsa_controller"... May 09 01:34:24 volumio sudo[12242]: pam_unix(sudo:session): session closed for user root May 09 01:34:24 volumio sudo[12245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 01:34:24 volumio sudo[12245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:34:24 volumio volumio[12213]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. May 09 01:34:24 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "wizard"... May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "networkfs"... May 09 01:34:24 volumio volumio[12213]: info: Starting Udev Watcher for removable devices May 09 01:34:24 volumio volumio[12213]: info: Ignoring mount for partition: bootfs May 09 01:34:24 volumio volumio[12213]: info: Ignoring mount for partition: rootfs May 09 01:34:24 volumio volumio[12213]: info: Ignoring mount for partition: boot May 09 01:34:24 volumio volumio[12213]: info: Ignoring mount for partition: volumio May 09 01:34:24 volumio volumio[12213]: info: Ignoring mount for partition: volumio_data May 09 01:34:24 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "volumio_command_line_client"... May 09 01:34:24 volumio volumio[12213]: info: Plugin upnp is not enabled May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "my_music"... May 09 01:34:24 volumio volumio[12213]: info: Loading plugin "mpd"... May 09 01:34:25 volumio volumio[12213]: info: Plugin upnp_browser is not enabled May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "alarm-clock"... May 09 01:34:25 volumio volumio[12213]: info: Plugin airplay_emulation is not enabled May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "last_100"... May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "webradio"... May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "i2s_dacs"... May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "volumiodiscovery"... May 09 01:34:25 volumio volumio[12213]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:34:25 volumio node[12213]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:34:25 volumio node[12213]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:34:25 volumio volumio[12213]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:34:25 volumio volumio[12213]: *** WARNING *** For more information see May 09 01:34:25 volumio volumio[12213]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:34:25 volumio volumio[12213]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:34:25 volumio volumio[12213]: *** WARNING *** For more information see May 09 01:34:25 volumio node[12213]: *** WARNING *** For more information see May 09 01:34:25 volumio node[12213]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:34:25 volumio node[12213]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:34:25 volumio node[12213]: *** WARNING *** For more information see May 09 01:34:25 volumio volumio[12213]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 01:34:25 volumio volumio[12213]: info: Discovery: Started advertising with name: Volumio May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "autostart"... May 09 01:34:25 volumio volumio[12213]: info: Applying required configuration parameters for plugin autostart May 09 01:34:25 volumio volumio[12213]: info: AutoStart - onVolumioStart - read config.json May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "outputs"... May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "albumart"... May 09 01:34:25 volumio volumio[12213]: info: Plugin example_plugin is not enabled May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "inputs"... May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "updater_comm"... May 09 01:34:25 volumio volumio[12213]: info: Plugin mpdemulation is not enabled May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "rest_api"... May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "websocket"... May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "fusiondsp"... May 09 01:34:25 volumio volumio[12213]: Forking 3 albumart workers May 09 01:34:25 volumio volumio[12213]: info: Applying required configuration parameters for plugin fusiondsp May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "personal_radio"... May 09 01:34:25 volumio volumio[12213]: info: ControllerPersonalRadio::constructor May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "audiophonicsonoff"... May 09 01:34:25 volumio volumio[12213]: Starting albumart workers May 09 01:34:25 volumio volumio[12213]: Starting albumart workers May 09 01:34:25 volumio volumio[12213]: info: Applying required configuration parameters for plugin audiophonicsonoff May 09 01:34:25 volumio volumio[12213]: info: Audiophonics on/off initiated May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "backup_restore"... May 09 01:34:25 volumio volumio[12213]: Starting albumart workers May 09 01:34:25 volumio volumio[12213]: info: Applying required configuration parameters for plugin backup_restore May 09 01:34:25 volumio volumio[12213]: info: Loading plugin "Systeminfo"... May 09 01:34:25 volumio volumio[12213]: info: Loading i18n strings for locale ko May 09 01:34:25 volumio volumio[12213]: Updating browse sources language May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::initPlayerControls May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:34:25 volumio volumio[12213]: Express server listening on port 3000 May 09 01:34:25 volumio volumio[12213]: [Metrics] WebUI: 1s 335.76ms May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::resetVolumioState May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::getcurrentVolume May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:34:25 volumio volumio[12213]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:34:25 volumio volumio[12213]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 1 May 09 01:34:25 volumio volumio[12213]: info: VolumeController:: Volume=undefined Mute =false May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::pushState May 09 01:34:25 volumio volumio[12213]: info: CorePlayQueue::getTrack 0 May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::volumioPushState May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::updateTrackBlock May 09 01:34:25 volumio volumio[12213]: info: CorePlayQueue::getTrackBlock May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:34:25 volumio volumio[12213]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::setRepeat true single undefined May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::pushState May 09 01:34:25 volumio volumio[12213]: info: CorePlayQueue::getTrack 0 May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::volumioPushState May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::setRandom true May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::pushState May 09 01:34:25 volumio volumio[12213]: info: CorePlayQueue::getTrack 0 May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::volumioPushState May 09 01:34:25 volumio volumio[12213]: info: Setting Device type: Raspberry PI May 09 01:34:25 volumio volumio[12213]: info: VolumeController:: Volume=undefined Mute =false May 09 01:34:25 volumio volumio[12213]: info: CoreStateMachine::pushState May 09 01:34:25 volumio volumio[12213]: info: CorePlayQueue::getTrack 0 May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::volumioPushState May 09 01:34:25 volumio volumio[12213]: info: Discovery: adding 1f8966a6-04ba-47db-aeca-a33edc5b5928 May 09 01:34:25 volumio volumio[12213]: info: Discovery: Found device Volumio May 09 01:34:25 volumio volumio[12213]: info: CoreCommandRouter::volumioGetState May 09 01:34:25 volumio volumio[12213]: info: CorePlayQueue::getTrack 0 May 09 01:34:27 volumio volumio[12213]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Total Clients: 2 May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:34:27 volumio volumio[12213]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 01:34:27 volumio volumio[12213]: info: Received Get System Version May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:34:27 volumio volumio[12213]: info: Received Get System Info May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 01:34:27 volumio volumio[12213]: info: Discovery: Getting this device information May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::volumioGetState May 09 01:34:27 volumio volumio[12213]: info: CorePlayQueue::getTrack 0 May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::volumioGetState May 09 01:34:27 volumio volumio[12213]: info: CorePlayQueue::getTrack 0 May 09 01:34:27 volumio volumio[12213]: info: CoreCommandRouter::volumioGetQueue May 09 01:34:27 volumio volumio[12213]: info: CoreStateMachine::getQueue May 09 01:34:27 volumio volumio[12213]: info: CorePlayQueue::getQueue May 09 01:34:27 volumio volumio[12213]: info: Listing playlists May 09 01:34:28 volumio volumio-remote-updater[644]: [2024-05-09 01:34:28] [connect] Successful connection May 09 01:34:28 volumio volumio-remote-updater[644]: [2024-05-09 01:34:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715186068 101 May 09 01:34:28 volumio volumio[12213]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 May 09 01:34:28 volumio sudo[12245]: pam_unix(sudo:session): session closed for user root May 09 01:34:31 volumio volumio[12213]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 01:34:52 volumio volumio[12213]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ May 09 01:34:52 volumio volumio[12213]: info: PLUGIN onShutdown : networkfs May 09 01:34:52 volumio volumio[12213]: info: PLUGIN onShutdown : audiophonicsonoff May 09 01:34:52 volumio volumio[12213]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:34:52 volumio volumio[12213]: TypeError: Cannot read property 'writeSync' of undefined May 09 01:34:52 volumio volumio[12213]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) May 09 01:34:52 volumio volumio[12213]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) May 09 01:34:52 volumio volumio[12213]: at HashMap. (/volumio/app/pluginmanager.js:621:31) May 09 01:34:52 volumio volumio[12213]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 09 01:34:52 volumio volumio[12213]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 09 01:34:52 volumio volumio[12213]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) May 09 01:34:52 volumio volumio[12213]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1313:24) May 09 01:34:52 volumio volumio[12213]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:831:33) May 09 01:34:52 volumio volumio[12213]: at Socket.emit (events.js:315:20) May 09 01:34:52 volumio volumio[12213]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 09 01:34:52 volumio volumio[12213]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:34:52 volumio sudo[12374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 01:33 May 09 01:34:52 volumio sudo[12374]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"