-- Logs begin at Sun 2023-11-26 09:11:08 UTC, end at Sat 2024-09-07 11:22:55 UTC. -- Sep 07 11:21:51 volumio systemd[1]: mpd.service stop-final-sigterm timed out. Killing. Sep 07 11:21:54 volumio systemd[1]: mpd.service: main process exited, code=killed, status=9/KILL Sep 07 11:21:54 volumio systemd[1]: Failed to start Music Player Daemon. Sep 07 11:21:54 volumio systemd[1]: Unit mpd.service entered failed state. Sep 07 11:21:54 volumio systemd[1]: Starting Music Player Daemon... Sep 07 11:22:31 volumio systemd[1]: Started Music Player Daemon. Sep 07 11:22:32 volumio systemd[1]: Starting Multi-User System. Sep 07 11:22:32 volumio systemd[1]: Reached target Multi-User System. Sep 07 11:22:32 volumio systemd[1]: Starting Graphical Interface. Sep 07 11:22:32 volumio systemd[1]: Reached target Graphical Interface. Sep 07 11:22:32 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 07 11:22:32 volumio volumio[752]: Upnp client error: Error: This socket has been ended by the other party Sep 07 11:22:32 volumio volumio[752]: Upnp client error: Error: This socket has been ended by the other party Sep 07 11:22:32 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 07 11:22:32 volumio systemd[1]: Startup finished in 11.026s (kernel) + 8min 25.585s (userspace) = 8min 36.611s. Sep 07 11:22:32 volumio volumio[752]: info: Sep 07 11:22:32 volumio volumio[752]: Upnp client error: Error: This socket has been ended by the other party Sep 07 11:22:32 volumio volumio[752]: Upnp client error: Error: This socket has been ended by the other party Sep 07 11:22:32 volumio volumio[752]: Upnp client error: Error: This socket has been ended by the other party Sep 07 11:22:32 volumio volumio[752]: Upnp client error: Error: This socket has been ended by the other party Sep 07 11:22:45 volumio volumio[752]: info: CoreCommandRouter::volumioPlay Sep 07 11:22:45 volumio volumio[752]: UNSET VOLATILE Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::play index undefined Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 11:22:45 volumio volumio[752]: info: CorePlayQueue::getTrack 0 Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::startPlaybackTimer Sep 07 11:22:45 volumio volumio[752]: info: CorePlayQueue::getTrack 0 Sep 07 11:22:45 volumio volumio[752]: info: [1725708165104] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Sep 07 11:22:45 volumio volumio[752]: info: [1725708165115] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Sep 07 11:22:45 volumio volumio[752]: info: [1725708165392] [80s80s] received new event containing 2 songs. Sep 07 11:22:45 volumio volumio[752]: info: ControllerMpd::sendMpdCommand stop Sep 07 11:22:45 volumio volumio[752]: info: sending command... Sep 07 11:22:45 volumio volumio[752]: info: parsing response... Sep 07 11:22:45 volumio volumio[752]: info: ControllerMpd::sendMpdCommand clear Sep 07 11:22:45 volumio volumio[752]: info: sending command... Sep 07 11:22:45 volumio volumio[752]: info: Sep 07 11:22:45 volumio volumio[752]: ---------------------------- MPD announces system playlist update Sep 07 11:22:45 volumio volumio[752]: info: Ignoring MPD Status Update Sep 07 11:22:45 volumio volumio[752]: info: parsing response... Sep 07 11:22:45 volumio volumio[752]: info: [1725708165510] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Sep 07 11:22:45 volumio volumio[752]: info: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Sep 07 11:22:45 volumio volumio[752]: info: sending command... Sep 07 11:22:45 volumio volumio[752]: info: Sep 07 11:22:45 volumio volumio[752]: info: Sep 07 11:22:45 volumio volumio[752]: ---------------------------- MPD announces system playlist update Sep 07 11:22:45 volumio volumio[752]: info: Ignoring MPD Status Update Sep 07 11:22:45 volumio volumio[752]: info: ------------------------------ 96ms Sep 07 11:22:45 volumio volumio[752]: info: parsing response... Sep 07 11:22:45 volumio volumio[752]: info: ControllerMpd::sendMpdCommand play Sep 07 11:22:45 volumio volumio[752]: info: sending command... Sep 07 11:22:45 volumio volumio[752]: info: ------------------------------ 84ms Sep 07 11:22:45 volumio volumio[752]: info: parsing response... Sep 07 11:22:45 volumio volumio[752]: info: [1725708165677] [80s80s] Pushing the next song state: Al Corley - Square Rooms Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::getState Sep 07 11:22:45 volumio volumio[752]: info: CorePlayQueue::getTrack 0 Sep 07 11:22:45 volumio volumio[752]: info: CoreCommandRouter::servicePushState Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::syncState Sep 07 11:22:45 volumio volumio[752]: info: CorePlayQueue::getTrack 0 Sep 07 11:22:45 volumio volumio[752]: info: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music125/v4/2b/9f/83/2b9f83a1-47ca-9a52-6b49-d270ee0085ba/source/600x600bb.jpg","name":"Al Corley - Square Rooms","title":"Square Rooms","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"201","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 07 11:22:45 volumio volumio[752]: info: CURRENT POSITION 0 Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::syncState stateService play Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::syncState currentStatus stop Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::pushState Sep 07 11:22:45 volumio volumio[752]: info: CoreStateMachine::getState Sep 07 11:22:45 volumio volumio[752]: info: CorePlayQueue::getTrack 0 Sep 07 11:22:45 volumio volumio[752]: info: CoreCommandRouter::volumioPushState Sep 07 11:22:45 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 11:22:46 volumio volumio[752]: info: [1725708166047] [80s80s] getContentOfUrl started with url http://ntp-a4.nict.go.jp/cgi-bin/json Sep 07 11:22:46 volumio sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 11:22:46 volumio sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 11:22:46 volumio volumio[752]: info: CorePlayQueue::getTrack 0 Sep 07 11:22:46 volumio volumio[752]: info: CorePlayQueue::getTrack 1 Sep 07 11:22:46 volumio volumio[752]: info: Prefetching next song Sep 07 11:22:46 volumio sudo[1174]: pam_unix(sudo:session): session closed for user root Sep 07 11:22:46 volumio volumio[752]: info: Sep 07 11:22:47 volumio volumio[752]: ---------------------------- MPD announces state update: player Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::getState Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::sendMpdCommand status Sep 07 11:22:47 volumio volumio[752]: info: sending command... Sep 07 11:22:47 volumio volumio[752]: info: Sep 07 11:22:47 volumio volumio[752]: ---------------------------- MPD announces state update: player Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::getState Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::sendMpdCommand status Sep 07 11:22:47 volumio volumio[752]: info: parsing response... Sep 07 11:22:47 volumio volumio[752]: info: sending command... Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::parseState Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::sendMpdCommand playlistinfo Sep 07 11:22:47 volumio volumio[752]: info: sending command... Sep 07 11:22:47 volumio volumio[752]: info: Sep 07 11:22:47 volumio volumio[752]: ---------------------------- MPD announces system playlist update Sep 07 11:22:47 volumio volumio[752]: info: Ignoring MPD Status Update Sep 07 11:22:47 volumio volumio[752]: info: Sep 07 11:22:47 volumio volumio[752]: ---------------------------- MPD announces system playlist update Sep 07 11:22:47 volumio volumio[752]: info: Ignoring MPD Status Update Sep 07 11:22:47 volumio volumio[752]: info: Sep 07 11:22:47 volumio volumio[752]: ---------------------------- MPD announces state update: player Sep 07 11:22:47 volumio volumio[752]: info: parsing response... Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::getState Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::sendMpdCommand status Sep 07 11:22:47 volumio volumio[752]: info: parsing response... Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::parseState Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::sendMpdCommand playlistinfo Sep 07 11:22:47 volumio volumio[752]: info: sending command... Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::parseTrackInfo Sep 07 11:22:47 volumio volumio[752]: info: sending command... Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::pushState Sep 07 11:22:47 volumio volumio[752]: info: CoreCommandRouter::servicePushState Sep 07 11:22:47 volumio volumio[752]: info: CoreStateMachine::syncState Sep 07 11:22:47 volumio volumio[752]: info: ERROR Prefetch 500ms setTimeout missed >> directly calling increasePlaybackTimer Sep 07 11:22:47 volumio volumio[752]: info: ERROR this.runPlaybackTimer:true this.currentSongDuration:201 - this.currentSeek:1596 - this.prefetchDone:false - this.simulateStopStartDone:false Sep 07 11:22:47 volumio volumio[752]: info: CorePlayQueue::getTrack 0 Sep 07 11:22:47 volumio volumio[752]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 07 11:22:47 volumio volumio[752]: info: ------------------------------ 920ms Sep 07 11:22:47 volumio volumio[752]: info: ------------------------------ 363ms Sep 07 11:22:47 volumio volumio[752]: info: ------------------------------ 350ms Sep 07 11:22:47 volumio volumio[752]: info: parsing response... Sep 07 11:22:47 volumio volumio[752]: info: parsing response... Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::parseState Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::sendMpdCommand playlistinfo Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::parseTrackInfo Sep 07 11:22:47 volumio volumio[752]: info: sending command... Sep 07 11:22:47 volumio volumio[752]: info: ControllerMpd::pushState Sep 07 11:22:47 volumio volumio[752]: info: CoreCommandRouter::servicePushState Sep 07 11:22:47 volumio volumio[752]: info: CoreStateMachine::syncState Sep 07 11:22:48 volumio volumio[752]: info: ERROR Prefetch 500ms setTimeout missed >> directly calling increasePlaybackTimer Sep 07 11:22:48 volumio volumio[752]: info: ERROR this.runPlaybackTimer:true this.currentSongDuration:201 - this.currentSeek:20147654 - this.prefetchDone:false - this.simulateStopStartDone:false Sep 07 11:22:48 volumio volumio[752]: info: CorePlayQueue::getTrack 0 Sep 07 11:22:48 volumio volumio[752]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 07 11:22:48 volumio volumio[752]: info: ------------------------------ 843ms Sep 07 11:22:48 volumio volumio[752]: info: [1725708168190] [80s80s] Failed to query api, status code: 404 Sep 07 11:22:48 volumio volumio[752]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 11:22:48 volumio volumio[752]: ReferenceError: station is not defined Sep 07 11:22:48 volumio volumio[752]: at ClientRequest.http.get (/data/plugins/music_service/80s80s/index.js:478:26) Sep 07 11:22:48 volumio volumio[752]: at Object.onceWrapper (events.js:315:30) Sep 07 11:22:48 volumio volumio[752]: at emitOne (events.js:116:13) Sep 07 11:22:48 volumio volumio[752]: at ClientRequest.emit (events.js:211:7) Sep 07 11:22:48 volumio volumio[752]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) Sep 07 11:22:48 volumio volumio[752]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) Sep 07 11:22:48 volumio volumio[752]: at Socket.socketOnData (_http_client.js:440:20) Sep 07 11:22:48 volumio volumio[752]: at emitOne (events.js:116:13) Sep 07 11:22:48 volumio volumio[752]: at Socket.emit (events.js:211:7) Sep 07 11:22:48 volumio volumio[752]: at addChunk (_stream_readable.js:263:12) Sep 07 11:22:48 volumio volumio[752]: at readableAddChunk (_stream_readable.js:250:11) Sep 07 11:22:48 volumio volumio[752]: at Socket.Readable.push (_stream_readable.js:208:10) Sep 07 11:22:48 volumio volumio[752]: at TCP.onread (net.js:607:20) Sep 07 11:22:48 volumio volumio[752]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 11:22:55 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-07 11:21 Sep 07 11:22:55 volumio sudo[1193]: 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="7441f93fb55fc13e1243278874bfebef55eaac89" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="60cbd8a1e03b2e464c11c01f54954168a34e9b45" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Feb 15 03:28:59 CET 2020" VOLUMIO_VERSION="2.703" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c6482eede154d29f2cc010e5a630bcb4"