-- Logs begin at Sat 2024-05-04 12:20:34 UTC, end at Sat 2024-05-04 12:48:30 UTC. -- May 04 12:47:44 volumio volumio[1118]: info: May 04 12:47:44 volumio volumio[1118]: ---------------------------- MPD announces system playlist update May 04 12:47:44 volumio volumio[1118]: info: Ignoring MPD Status Update May 04 12:47:44 volumio volumio[1118]: info: May 04 12:47:44 volumio volumio[1118]: ---------------------------- MPD announces state update: player May 04 12:47:44 volumio volumio[1118]: info: ControllerMpd::getState May 04 12:47:44 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand status May 04 12:47:44 volumio volumio[1118]: info: ------------------------------ 4ms May 04 12:47:44 volumio volumio[1118]: info: sendMpdCommand status took 2 milliseconds May 04 12:47:44 volumio volumio[1118]: verbose: ControllerMpd::parseState May 04 12:47:44 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 12:47:44 volumio volumio[1118]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 12:47:44 volumio volumio[1118]: verbose: ControllerMpd::parseTrackInfo May 04 12:47:44 volumio volumio[1118]: info: ControllerMpd::pushState May 04 12:47:44 volumio volumio[1118]: info: CoreCommandRouter::servicePushState May 04 12:47:44 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 May 04 12:47:44 volumio volumio[1118]: verbose: STATE SERVICE {"status":"play","position":0,"seek":153277,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Beach Cafe - Grooves Del Mar (Sundowner Mix)","artist":"ANTENNE BAYERN Lounge","album":null,"uri":"http://stream.antenne.de:80/lounge","trackType":""} May 04 12:47:44 volumio volumio[1118]: verbose: CURRENT POSITION 0 May 04 12:47:44 volumio volumio[1118]: info: CoreStateMachine::syncState stateService play May 04 12:47:44 volumio volumio[1118]: info: CoreStateMachine::syncState currentStatus play May 04 12:47:44 volumio volumio[1118]: info: Received an update from plugin. extracting info from payload May 04 12:47:44 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 12:47:44 volumio volumio[1118]: info: CoreStateMachine::pushState May 04 12:47:44 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState May 04 12:47:44 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 12:47:44 volumio volumio[1118]: info: CoreStateMachine::pushState May 04 12:47:44 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState May 04 12:47:44 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 12:47:45 volumio volumio[1118]: info: ------------------------------ 19ms May 04 12:47:47 volumio volumio[1118]: info: May 04 12:47:47 volumio volumio[1118]: ---------------------------- MPD announces system playlist update May 04 12:47:47 volumio volumio[1118]: info: Ignoring MPD Status Update May 04 12:47:47 volumio volumio[1118]: info: May 04 12:47:47 volumio volumio[1118]: ---------------------------- MPD announces state update: player May 04 12:47:47 volumio volumio[1118]: info: ControllerMpd::getState May 04 12:47:47 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand status May 04 12:47:47 volumio volumio[1118]: info: ------------------------------ 4ms May 04 12:47:47 volumio volumio[1118]: info: sendMpdCommand status took 4 milliseconds May 04 12:47:47 volumio volumio[1118]: verbose: ControllerMpd::parseState May 04 12:47:47 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 12:47:47 volumio volumio[1118]: info: sendMpdCommand playlistinfo took 6 milliseconds May 04 12:47:47 volumio volumio[1118]: verbose: ControllerMpd::parseTrackInfo May 04 12:47:47 volumio volumio[1118]: info: ControllerMpd::pushState May 04 12:47:47 volumio volumio[1118]: info: CoreCommandRouter::servicePushState May 04 12:47:47 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 May 04 12:47:47 volumio volumio[1118]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156189,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Beach Cafe - Grooves Del Mar (Sundowner Mix)","artist":"ANTENNE BAYERN Lounge","album":null,"uri":"http://stream.antenne.de:80/lounge","trackType":""} May 04 12:47:47 volumio volumio[1118]: verbose: CURRENT POSITION 0 May 04 12:47:47 volumio volumio[1118]: info: CoreStateMachine::syncState stateService play May 04 12:47:47 volumio volumio[1118]: info: CoreStateMachine::syncState currentStatus play May 04 12:47:47 volumio volumio[1118]: info: Received an update from plugin. extracting info from payload May 04 12:47:47 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 12:47:47 volumio volumio[1118]: info: CoreStateMachine::pushState May 04 12:47:47 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState May 04 12:47:47 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 12:47:47 volumio volumio[1118]: info: CoreStateMachine::pushState May 04 12:47:47 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState May 04 12:47:47 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 12:47:47 volumio volumio[1118]: info: ------------------------------ 28ms May 04 12:47:50 volumio volumio[1118]: info: May 04 12:47:50 volumio volumio[1118]: ---------------------------- MPD announces system playlist update May 04 12:47:50 volumio volumio[1118]: info: Ignoring MPD Status Update May 04 12:47:50 volumio volumio[1118]: info: May 04 12:47:50 volumio volumio[1118]: ---------------------------- MPD announces state update: player May 04 12:47:50 volumio volumio[1118]: info: ControllerMpd::getState May 04 12:47:50 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand status May 04 12:47:50 volumio volumio[1118]: info: ------------------------------ 2ms May 04 12:47:50 volumio volumio[1118]: info: sendMpdCommand status took 2 milliseconds May 04 12:47:50 volumio volumio[1118]: verbose: ControllerMpd::parseState May 04 12:47:50 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 12:47:50 volumio volumio[1118]: info: sendMpdCommand playlistinfo took 8 milliseconds May 04 12:47:50 volumio volumio[1118]: verbose: ControllerMpd::parseTrackInfo May 04 12:47:50 volumio volumio[1118]: info: ControllerMpd::pushState May 04 12:47:50 volumio volumio[1118]: info: CoreCommandRouter::servicePushState May 04 12:47:50 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 May 04 12:47:50 volumio volumio[1118]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158188,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Beach Cafe - Grooves Del Mar (Sundowner Mix)","artist":"ANTENNE BAYERN Lounge","album":null,"uri":"http://stream.antenne.de:80/lounge","trackType":""} May 04 12:47:50 volumio volumio[1118]: verbose: CURRENT POSITION 0 May 04 12:47:50 volumio volumio[1118]: info: CoreStateMachine::syncState stateService play May 04 12:47:50 volumio volumio[1118]: info: CoreStateMachine::syncState currentStatus play May 04 12:47:50 volumio volumio[1118]: info: Received an update from plugin. extracting info from payload May 04 12:47:50 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 12:47:50 volumio volumio[1118]: info: CoreStateMachine::pushState May 04 12:47:50 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState May 04 12:47:50 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 12:47:50 volumio volumio[1118]: info: CoreStateMachine::pushState May 04 12:47:50 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState May 04 12:47:50 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 12:47:50 volumio volumio[1118]: info: ------------------------------ 21ms May 04 12:47:51 volumio volumio[1118]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/now_playing.zip May 04 12:47:51 volumio volumio[1118]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/now_playing.zip May 04 12:47:51 volumio volumio[1118]: info: Folder /tmp/plugins removed May 04 12:47:52 volumio volumio[1118]: info: Checking if plugin already exists May 04 12:47:52 volumio volumio[1118]: info: Rename folder May 04 12:47:52 volumio volumio[1118]: info: Move to category May 04 12:47:52 volumio volumio[1118]: info: Folder /tmp/downloaded_plugin.zip removed May 04 12:47:54 volumio volumio[1118]: info: Checking if install.sh is present May 04 12:47:54 volumio volumio[1118]: info: Executing install.sh May 04 12:47:54 volumio sudo[5656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh May 04 12:47:54 volumio sudo[5656]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 12:47:54 volumio volumio[1118]: info: Installing geo-tz node dependency May 04 12:48:02 volumio volumio[1118]: info: Creating user directories (if not exist) May 04 12:48:02 volumio sudo[5656]: pam_unix(sudo:session): session closed for user root May 04 12:48:02 volumio volumio[1118]: info: Now Playing plugin installed May 04 12:48:02 volumio volumio[1118]: Plugin install end detected on script May 04 12:48:02 volumio volumio[1118]: info: Install script completed May 04 12:48:02 volumio volumio[1118]: info: Adding reference to registry May 04 12:48:02 volumio volumio[1118]: info: Done installing plugin. May 04 12:48:02 volumio volumio[1118]: info: Folder /tmp/plugins removed May 04 12:48:02 volumio volumio[1118]: info: Folder /tmp/downloaded_plugin.zip removed May 04 12:48:02 volumio volumio[1118]: info: Folder /data/temp removed May 04 12:48:13 volumio volumio[1118]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT May 04 12:48:30 volumio volumio[1118]: info: Enabling plugin now_playing May 04 12:48:30 volumio volumio[1118]: info: Loading plugin "now_playing"... May 04 12:48:30 volumio volumio[1118]: error: !!!! WARNING !!!! May 04 12:48:30 volumio volumio[1118]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: SyntaxError: Unexpected token . May 04 12:48:30 volumio volumio[1118]: error: Stack trace: /data/plugins/user_interface/now_playing/dist/index.js:405 May 04 12:48:30 volumio volumio[1118]: const backupName = data.backupName?.trim(); May 04 12:48:30 volumio volumio[1118]: ^ May 04 12:48:30 volumio volumio[1118]: SyntaxError: Unexpected token . May 04 12:48:30 volumio volumio[1118]: at createScript (vm.js:80:10) May 04 12:48:30 volumio volumio[1118]: at Object.runInThisContext (vm.js:139:10) May 04 12:48:30 volumio volumio[1118]: at Module._compile (module.js:616:28) May 04 12:48:30 volumio volumio[1118]: at Object.Module._extensions..js (module.js:663:10) May 04 12:48:30 volumio volumio[1118]: at Module.load (module.js:565:32) May 04 12:48:30 volumio volumio[1118]: at tryModuleLoad (module.js:505:12) May 04 12:48:30 volumio volumio[1118]: at Function.Module._load (module.js:497:3) May 04 12:48:30 volumio volumio[1118]: at Module.require (module.js:596:17) May 04 12:48:30 volumio volumio[1118]: at require (internal/module.js:11:18) May 04 12:48:30 volumio volumio[1118]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:152:29) May 04 12:48:30 volumio volumio[1118]: at Promise._successFn (/volumio/app/pluginmanager.js:1548:19) May 04 12:48:30 volumio volumio[1118]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 04 12:48:30 volumio volumio[1118]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 04 12:48:30 volumio volumio[1118]: at process._tickCallback (internal/process/next_tick.js:180:9) May 04 12:48:30 volumio volumio[1118]: error: !!!! WARNING !!!! May 04 12:48:30 volumio volumio[1118]: info: Done. May 04 12:48:30 volumio volumio[1118]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 12:48:30 volumio volumio[1118]: TypeError: Cannot read property 'broadcastMessage' of null May 04 12:48:30 volumio volumio[1118]: at /volumio/app/index.js:1429:32 May 04 12:48:30 volumio volumio[1118]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) May 04 12:48:30 volumio volumio[1118]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) May 04 12:48:30 volumio volumio[1118]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1427:13) May 04 12:48:30 volumio volumio[1118]: at Socket.livelogchild.stdout.on (/volumio/app/plugins/system_controller/system/index.js:1100:28) May 04 12:48:30 volumio volumio[1118]: at emitOne (events.js:116:13) May 04 12:48:30 volumio volumio[1118]: at Socket.emit (events.js:211:7) May 04 12:48:30 volumio volumio[1118]: at addChunk (_stream_readable.js:263:12) May 04 12:48:30 volumio volumio[1118]: at readableAddChunk (_stream_readable.js:250:11) May 04 12:48:30 volumio volumio[1118]: at Socket.Readable.push (_stream_readable.js:208:10) May 04 12:48:30 volumio volumio[1118]: at Pipe.onread (net.js:607:20) May 04 12:48:30 volumio volumio[1118]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 12:48:30 volumio sudo[5789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 12:47 May 04 12:48:30 volumio sudo[5789]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"