-- Logs begin at Mon 2024-10-07 09:52:13 UTC, end at Mon 2024-10-07 09:54:25 UTC. -- Oct 07 09:53:02 volumio volumio[898]: info: CoreCommandRouter::volumioSeek Oct 07 09:53:02 volumio volumio[898]: info: CoreStateMachine::seek Oct 07 09:53:02 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:02 volumio volumio[898]: info: TRACKBLOCK {"service":"webradio","type":"track","uri":"https://radio.anothermusicproject.com:8443/idm","name":"Another Music Project","albumart":"https://cdn-profiles.tunein.com/s238473/images/logoq.png?t=638117273820000000","samplerate":"","bitdepth":"","channels":0,"trackType":"webradio"} Oct 07 09:53:02 volumio volumio[898]: info: CoreStateMachine::startPlaybackTimer Oct 07 09:53:02 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:02 volumio volumio[898]: info: [1728294782004] ControllerWebradio::seek Oct 07 09:53:02 volumio volumio[898]: info: ControllerMpd::seek Oct 07 09:53:02 volumio volumio[898]: info: CoreStateMachine::pushState Oct 07 09:53:02 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:02 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Oct 07 09:53:02 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 09:53:02 volumio volumio[898]: error: [2@0] {} Bad song index Oct 07 09:53:03 volumio volumio[898]: info: CoreCommandRouter::volumioPlay Oct 07 09:53:03 volumio volumio[898]: UNSET VOLATILE Oct 07 09:53:03 volumio volumio[898]: info: CoreStateMachine::play index undefined Oct 07 09:53:03 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 07 09:53:03 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:03 volumio volumio[898]: info: CoreStateMachine::startPlaybackTimer Oct 07 09:53:03 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:03 volumio volumio[898]: info: [1728294783921] ControllerWebradio::clearAddPlayTrack Oct 07 09:53:03 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand stop Oct 07 09:53:03 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand clear Oct 07 09:53:03 volumio volumio[898]: info: Oct 07 09:53:03 volumio volumio[898]: ---------------------------- MPD announces system playlist update Oct 07 09:53:03 volumio volumio[898]: info: Ignoring MPD Status Update Oct 07 09:53:03 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand load "https://radio.anothermusicproject.com:8443/idm" Oct 07 09:53:03 volumio volumio[898]: error: updateQueue error: null Oct 07 09:53:03 volumio volumio[898]: info: ------------------------------ 1ms Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand add "https://radio.anothermusicproject.com:8443/idm" Oct 07 09:53:04 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand play Oct 07 09:53:04 volumio volumio[898]: info: Oct 07 09:53:04 volumio volumio[898]: ---------------------------- MPD announces system playlist update Oct 07 09:53:04 volumio volumio[898]: info: Ignoring MPD Status Update Oct 07 09:53:04 volumio volumio[898]: info: ------------------------------ 1ms Oct 07 09:53:04 volumio volumio[898]: info: Oct 07 09:53:04 volumio volumio[898]: ---------------------------- MPD announces state update: player Oct 07 09:53:04 volumio volumio[898]: info: ControllerMpd::getState Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Oct 07 09:53:04 volumio volumio[898]: info: Oct 07 09:53:04 volumio volumio[898]: ---------------------------- MPD announces system playlist update Oct 07 09:53:04 volumio volumio[898]: info: Ignoring MPD Status Update Oct 07 09:53:04 volumio volumio[898]: info: Oct 07 09:53:04 volumio volumio[898]: ---------------------------- MPD announces state update: player Oct 07 09:53:04 volumio volumio[898]: info: ControllerMpd::getState Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::parseState Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 07 09:53:04 volumio volumio[898]: info: ------------------------------ 4ms Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::parseState Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Oct 07 09:53:04 volumio volumio[898]: info: ControllerMpd::pushState Oct 07 09:53:04 volumio volumio[898]: info: CoreCommandRouter::servicePushState Oct 07 09:53:04 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:04 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"proem - you shall have ever been pt.1","artist":"Another Music Project","album":null,"uri":"https://radio.anothermusicproject.com:8443/idm","trackType":""} Oct 07 09:53:04 volumio volumio[898]: verbose: CURRENT POSITION 0 Oct 07 09:53:04 volumio volumio[898]: info: CoreStateMachine::syncState stateService play Oct 07 09:53:04 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Oct 07 09:53:04 volumio volumio[898]: info: CoreStateMachine::pushState Oct 07 09:53:04 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Oct 07 09:53:04 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 09:53:04 volumio volumio[898]: info: ------------------------------ 30ms Oct 07 09:53:04 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Oct 07 09:53:04 volumio volumio[898]: info: ControllerMpd::pushState Oct 07 09:53:04 volumio volumio[898]: info: CoreCommandRouter::servicePushState Oct 07 09:53:04 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:04 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"proem - you shall have ever been pt.1","artist":"Another Music Project","album":null,"uri":"https://radio.anothermusicproject.com:8443/idm","trackType":""} Oct 07 09:53:04 volumio volumio[898]: verbose: CURRENT POSITION 0 Oct 07 09:53:04 volumio volumio[898]: info: CoreStateMachine::syncState stateService play Oct 07 09:53:04 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play Oct 07 09:53:04 volumio volumio[898]: info: Received an update from plugin. extracting info from payload Oct 07 09:53:04 volumio volumio[898]: info: CoreStateMachine::pushState Oct 07 09:53:04 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Oct 07 09:53:04 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 09:53:04 volumio volumio[898]: info: CoreStateMachine::pushState Oct 07 09:53:04 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Oct 07 09:53:04 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 09:53:04 volumio volumio[898]: info: ------------------------------ 44ms Oct 07 09:53:05 volumio volumio[898]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86&variante=volumio&version=2.799&uuid=48bb623bbcac7dc45c77cead1c64fde9" http://updates.volumio.org:7070/downloader-v1/track-device Oct 07 09:53:05 volumio volumio[898]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 07 09:53:05 volumio volumio[898]: Dload Upload Total Spent Left Speed Oct 07 09:53:05 volumio volumio[898]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Oct 07 09:53:05 volumio volumio[898]: retrying in 5 seconds, trial 2 Oct 07 09:53:05 volumio volumio[898]: Volumio Calling Home Oct 07 09:53:11 volumio volumio[898]: info: CoreCommandRouter::volumioStop Oct 07 09:53:11 volumio volumio[898]: info: CoreStateMachine::stop Oct 07 09:53:11 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 07 09:53:11 volumio volumio[898]: UNSET VOLATILE Oct 07 09:53:11 volumio volumio[898]: info: CoreStateMachine::stPlaybackTimer Oct 07 09:53:11 volumio volumio[898]: info: CoreStateMachine::updateTrackBlock Oct 07 09:53:11 volumio volumio[898]: info: CorePlayQueue::getTrackBlock Oct 07 09:53:11 volumio volumio[898]: info: CoreStateMachine::pushState Oct 07 09:53:11 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:11 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Oct 07 09:53:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 09:53:11 volumio volumio[898]: info: CoreStateMachine::serviceStop Oct 07 09:53:11 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:11 volumio volumio[898]: info: CoreCommandRouter::serviceStop Oct 07 09:53:11 volumio volumio[898]: info: [1728294791480] ControllerWebradio::stop Oct 07 09:53:11 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand stop Oct 07 09:53:11 volumio volumio[898]: info: Oct 07 09:53:11 volumio volumio[898]: ---------------------------- MPD announces state update: player Oct 07 09:53:11 volumio volumio[898]: info: ControllerMpd::getState Oct 07 09:53:11 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Oct 07 09:53:11 volumio volumio[898]: verbose: ControllerMpd::parseState Oct 07 09:53:11 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 07 09:53:11 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Oct 07 09:53:11 volumio volumio[898]: info: ControllerMpd::pushState Oct 07 09:53:11 volumio volumio[898]: info: CoreCommandRouter::servicePushState Oct 07 09:53:11 volumio volumio[898]: info: CoreStateMachine::pushState Oct 07 09:53:11 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:11 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Oct 07 09:53:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 09:53:11 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Oct 07 09:53:11 volumio volumio[898]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 07 09:53:11 volumio volumio[898]: info: ------------------------------ 8ms Oct 07 09:53:32 volumio sshd[1254]: Accepted password for volumio from 192.168.1.121 port 54241 ssh2 Oct 07 09:53:32 volumio sshd[1254]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Oct 07 09:53:32 volumio systemd[1]: Starting user-1000.slice. Oct 07 09:53:32 volumio systemd[1]: Created slice user-1000.slice. Oct 07 09:53:32 volumio systemd-logind[666]: New session 1 of user volumio. Oct 07 09:53:32 volumio systemd[1]: Starting Session 1 of user volumio. Oct 07 09:53:32 volumio systemd[1]: Started Session 1 of user volumio. Oct 07 09:53:32 volumio systemd[1]: Starting User Manager for UID 1000... Oct 07 09:53:32 volumio systemd[1272]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Oct 07 09:53:32 volumio systemd[1272]: Starting Paths. Oct 07 09:53:32 volumio systemd[1272]: Reached target Paths. Oct 07 09:53:32 volumio systemd[1272]: Starting Timers. Oct 07 09:53:32 volumio systemd[1272]: Reached target Timers. Oct 07 09:53:32 volumio systemd[1272]: Starting Sockets. Oct 07 09:53:32 volumio systemd[1272]: Reached target Sockets. Oct 07 09:53:32 volumio systemd[1272]: Starting Basic System. Oct 07 09:53:32 volumio systemd[1272]: Reached target Basic System. Oct 07 09:53:32 volumio systemd[1272]: Starting Default. Oct 07 09:53:32 volumio systemd[1272]: Reached target Default. Oct 07 09:53:32 volumio systemd[1272]: Startup finished in 22ms. Oct 07 09:53:32 volumio systemd[1]: Started User Manager for UID 1000. Oct 07 09:53:32 volumio sshd[1276]: error: Failed to allocate internet-domain X11 display socket. Oct 07 09:53:33 volumio sshd[1254]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory Oct 07 09:53:33 volumio sshd[1254]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory Oct 07 09:53:33 volumio sshd[1271]: Accepted password for volumio from 192.168.1.121 port 54249 ssh2 Oct 07 09:53:33 volumio sshd[1271]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Oct 07 09:53:33 volumio systemd[1]: Starting Session 2 of user volumio. Oct 07 09:53:33 volumio systemd[1]: Started Session 2 of user volumio. Oct 07 09:53:33 volumio systemd-logind[666]: New session 2 of user volumio. Oct 07 09:54:16 volumio sshd[1372]: Accepted password for volumio from 192.168.1.121 port 54263 ssh2 Oct 07 09:54:16 volumio sshd[1372]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Oct 07 09:54:16 volumio systemd[1]: Starting Session 3 of user volumio. Oct 07 09:54:16 volumio systemd[1]: Started Session 3 of user volumio. Oct 07 09:54:16 volumio systemd-logind[666]: New session 3 of user volumio. Oct 07 09:54:18 volumio sshd[1372]: pam_unix(sshd:session): session closed for user volumio Oct 07 09:54:18 volumio systemd-logind[666]: Removed session 3. Oct 07 09:54:25 volumio volumio[898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 09:54:25 volumio volumio[898]: TypeError: Cannot read property 'plugin_type' of undefined Oct 07 09:54:25 volumio volumio[898]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1346:56) Oct 07 09:54:25 volumio volumio[898]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1438:29) Oct 07 09:54:25 volumio volumio[898]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1161:45) Oct 07 09:54:25 volumio volumio[898]: at emitTwo (events.js:126:13) Oct 07 09:54:25 volumio volumio[898]: at Socket.emit (events.js:214:7) Oct 07 09:54:25 volumio volumio[898]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 07 09:54:25 volumio volumio[898]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Oct 07 09:54:25 volumio volumio[898]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 07 09:54:25 volumio volumio[898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 09:54:25 volumio sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 09:53 Oct 07 09:54:25 volumio sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="79c2e7e29d04526018e922852ec460ffc0707dd1" VOLUMIO_FE_VERSION="77c86e8a1bfb19b724ead35c47d417c4832cf290" VOLUMIO_BE_VERSION="f30dbcf35403287bd1b5d6a60fa82e3da7871c6c" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jul 16 18:57:30 CEST 2020" VOLUMIO_VERSION="2.799" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="5c4ecf8094cc23791be364fa44700bc6"