-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2024-10-25 18:03:06 CEST. -- Oct 25 18:02:00 volumio-3 volumio[824]: info: Enabling plugin audiophonicsonoff Oct 25 18:02:00 volumio-3 volumio[824]: info: Loading plugin "audiophonicsonoff"... Oct 25 18:02:00 volumio-3 volumio[824]: info: Audiophonics on/off initiated Oct 25 18:02:00 volumio-3 volumio[824]: info: PLUGIN START: audiophonicsonoff Oct 25 18:02:00 volumio-3 volumio[824]: info: Configuring GPIO pins Oct 25 18:02:00 volumio-3 volumio[824]: info: Error: Error: EBUSY: resource busy or locked, write Oct 25 18:02:05 volumio-3 volumio[824]: info: Enabling plugin audiophonicsonoff Oct 25 18:02:05 volumio-3 volumio[824]: info: Loading plugin "audiophonicsonoff"... Oct 25 18:02:05 volumio-3 volumio[824]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 25 18:02:05 volumio-3 volumio[824]: info: Audiophonics on/off initiated Oct 25 18:02:05 volumio-3 volumio[824]: info: PLUGIN START: audiophonicsonoff Oct 25 18:02:05 volumio-3 volumio[824]: info: Configuring GPIO pins Oct 25 18:02:05 volumio-3 volumio[824]: info: Error: Error: EBUSY: resource busy or locked, write Oct 25 18:02:13 volumio-3 volumio[824]: info: Enabling plugin audiophonicsonoff Oct 25 18:02:13 volumio-3 volumio[824]: info: Loading plugin "audiophonicsonoff"... Oct 25 18:02:13 volumio-3 volumio[824]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 25 18:02:13 volumio-3 volumio[824]: info: Audiophonics on/off initiated Oct 25 18:02:13 volumio-3 volumio[824]: info: PLUGIN START: audiophonicsonoff Oct 25 18:02:13 volumio-3 volumio[824]: info: Configuring GPIO pins Oct 25 18:02:13 volumio-3 volumio[824]: info: Error: Error: EBUSY: resource busy or locked, write Oct 25 18:02:15 volumio-3 volumio[824]: info: Enabling plugin audiophonicsonoff Oct 25 18:02:15 volumio-3 volumio[824]: info: Loading plugin "audiophonicsonoff"... Oct 25 18:02:15 volumio-3 volumio[824]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 25 18:02:15 volumio-3 volumio[824]: info: Audiophonics on/off initiated Oct 25 18:02:15 volumio-3 volumio[824]: info: PLUGIN START: audiophonicsonoff Oct 25 18:02:15 volumio-3 volumio[824]: info: Configuring GPIO pins Oct 25 18:02:15 volumio-3 volumio[824]: info: Error: Error: EBUSY: resource busy or locked, write Oct 25 18:02:27 volumio-3 volumio[824]: info: Enabling plugin audiophonicsonoff Oct 25 18:02:27 volumio-3 volumio[824]: info: Loading plugin "audiophonicsonoff"... Oct 25 18:02:27 volumio-3 volumio[824]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 25 18:02:27 volumio-3 volumio[824]: info: Audiophonics on/off initiated Oct 25 18:02:27 volumio-3 volumio[824]: info: PLUGIN START: audiophonicsonoff Oct 25 18:02:27 volumio-3 volumio[824]: info: Configuring GPIO pins Oct 25 18:02:27 volumio-3 volumio[824]: info: Error: Error: EBUSY: resource busy or locked, write Oct 25 18:02:31 volumio-3 volumio[824]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 18:02:31 volumio-3 volumio[824]: info: Loaded the previous config. Oct 25 18:02:31 volumio-3 volumio[824]: info: ## populating UI... Oct 25 18:02:31 volumio-3 volumio[824]: info: 1/1 settings loaded Oct 25 18:02:31 volumio-3 volumio[824]: info: Populated config screen. Oct 25 18:02:45 volumio-3 volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 18:02:45 volumio-3 volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 18:02:49 volumio-3 volumiologrotate[648]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 25 18:02:49 volumio-3 volumiologrotate[648]: ls: cannot access '3': No such file or directory Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreCommandRouter::volumioPause Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreStateMachine::pause Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreStateMachine::stPlaybackTimer Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreStateMachine::servicePause Oct 25 18:02:59 volumio-3 volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreCommandRouter::servicePause Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::sendMpdCommand pause Oct 25 18:02:59 volumio-3 volumio[824]: info: sendMpdCommand pause took 2 milliseconds Oct 25 18:02:59 volumio-3 volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 18:02:59 volumio-3 volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002J09.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"When The Levee Breaks","title":"When The Levee Breaks","artist":"Led Zeppelin","album":"Led Zeppelin IV","streaming":true,"disableUiControls":true,"duration":421,"seek":171175,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 25 18:02:59 volumio-3 volumio[824]: verbose: CURRENT POSITION 0 Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreStateMachine::syncState stateService pause Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreStateMachine::syncState currentStatus pause Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreStateMachine::pushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreStateMachine::stPlaybackTimer Oct 25 18:02:59 volumio-3 volumio[824]: info: Oct 25 18:02:59 volumio-3 volumio[824]: ---------------------------- MPD announces state update: player Oct 25 18:02:59 volumio-3 volumio[824]: info: ControllerMpd::getState Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 18:02:59 volumio-3 volumio[824]: info: Oct 25 18:02:59 volumio-3 volumio[824]: ---------------------------- MPD announces state update: player Oct 25 18:02:59 volumio-3 volumio[824]: info: ControllerMpd::getState Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 18:02:59 volumio-3 volumio[824]: info: Oct 25 18:02:59 volumio-3 volumio[824]: ---------------------------- MPD announces state update: player Oct 25 18:02:59 volumio-3 volumio[824]: info: ControllerMpd::getState Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 18:02:59 volumio-3 volumio[824]: info: sendMpdCommand status took 6 milliseconds Oct 25 18:02:59 volumio-3 volumio[824]: info: sendMpdCommand status took 6 milliseconds Oct 25 18:02:59 volumio-3 volumio[824]: info: sendMpdCommand status took 6 milliseconds Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::parseState Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::parseState Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::parseState Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 18:02:59 volumio-3 volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 18:02:59 volumio-3 volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 18:02:59 volumio-3 volumio[824]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 18:02:59 volumio-3 volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 18:02:59 volumio-3 volumio[824]: info: ControllerMpd::pushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 18:02:59 volumio-3 volumio[824]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 25 18:02:59 volumio-3 volumio[824]: info: ControllerMpd::pushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 18:02:59 volumio-3 volumio[824]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 25 18:02:59 volumio-3 volumio[824]: info: ControllerMpd::pushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 18:02:59 volumio-3 volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 18:02:59 volumio-3 volumio[824]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 25 18:02:59 volumio-3 volumio[824]: info: ------------------------------ 12ms Oct 25 18:02:59 volumio-3 volumio[824]: info: ------------------------------ 11ms Oct 25 18:02:59 volumio-3 volumio[824]: info: ------------------------------ 10ms Oct 25 18:03:05 volumio-3 volumio[824]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 25 18:03:05 volumio-3 volumio[824]: info: PLUGIN onReboot : networkfs Oct 25 18:03:05 volumio-3 volumio[824]: info: PLUGIN onReboot : touch_display Oct 25 18:03:05 volumio-3 sudo[3464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 25 18:03:05 volumio-3 sudo[3464]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:03:05 volumio-3 sudo[3464]: pam_unix(sudo:session): session closed for user root Oct 25 18:03:05 volumio-3 volumio[824]: info: PLUGIN onReboot : audiophonicsonoff Oct 25 18:03:05 volumio-3 volumio[824]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 18:03:05 volumio-3 volumio[824]: TypeError: Cannot read property 'writeSync' of undefined Oct 25 18:03:05 volumio-3 volumio[824]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 25 18:03:05 volumio-3 volumio[824]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 25 18:03:05 volumio-3 volumio[824]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 25 18:03:05 volumio-3 volumio[824]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 25 18:03:05 volumio-3 volumio[824]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 25 18:03:05 volumio-3 volumio[824]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 25 18:03:05 volumio-3 volumio[824]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 25 18:03:05 volumio-3 volumio[824]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 25 18:03:05 volumio-3 volumio[824]: at Socket.emit (events.js:315:20) Oct 25 18:03:05 volumio-3 volumio[824]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 25 18:03:05 volumio-3 volumio[824]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 25 18:03:05 volumio-3 volumio[824]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 18:03:05 volumio-3 sudo[3467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Oct 25 18:03:05 volumio-3 sudo[3467]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 18:03:05 volumio-3 sudo[3467]: pam_unix(sudo:session): session closed for user root Oct 25 18:03:06 volumio-3 sudo[3478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-25 18:02 Oct 25 18:03:06 volumio-3 sudo[3478]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"