-- Logs begin at Thu 2024-05-09 01:31:39 KST, end at Thu 2024-05-09 01:34:17 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)
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"