-- Logs begin at Mon 2023-12-04 22:21:24 UTC, end at Wed 2024-04-24 16:52:06 UTC. -- Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::getState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand status Apr 24 16:51:49 volumio volumio[1003]: info: Apr 24 16:51:49 volumio volumio[1003]: ---------------------------- MPD announces state update: player Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::getState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand status Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: parsing response... Apr 24 16:51:49 volumio volumio[1003]: info: parsing response... Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::parseState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::parseState Apr 24 16:51:49 volumio volumio[1003]: info: STATE: status=stop, position=null, seek=null, duration=null, samplerate=null, bitdepth=null, channels=null, random=false, updatedb=false, repeat=false, isStreaming=false, title=null, artist=null, album=null, uri=null Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::syncState Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::getState Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 29 Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 29 Apr 24 16:51:49 volumio volumio[1003]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 24 16:51:49 volumio volumio[1003]: info: CURRENT POSITION 29 Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::syncState stateService stop Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play Apr 24 16:51:49 volumio volumio[1003]: info: CURRENT POSITION 29 Apr 24 16:51:49 volumio volumio[1003]: info: CURRENT POSITION 30 Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::play index undefined Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::getState Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 16:51:49 volumio sudo[2176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 16:51:49 volumio sudo[2176]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::pushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::syncState Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::getState Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 16:51:49 volumio sudo[2182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 16:51:49 volumio sudo[2176]: pam_unix(sudo:session): session closed for user root Apr 24 16:51:49 volumio sudo[2182]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:49 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ym Received mpd Apr 24 16:51:49 volumio sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:49 volumio sudo[2182]: pam_unix(sudo:session): session closed for user root Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::startPlaybackTimer Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::addPlay Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommandArray Apr 24 16:51:49 volumio sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 16:51:49 volumio volumio[1003]: info: ------------------------------ 109ms Apr 24 16:51:49 volumio volumio[1003]: info: COMMAND [object Object] Apr 24 16:51:49 volumio volumio[1003]: info: COMMAND [object Object] Apr 24 16:51:49 volumio volumio[1003]: info: COMMAND [object Object] Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::getState Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 24 16:51:49 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 16:51:49 volumio sudo[2188]: pam_unix(sudo:session): session closed for user root Apr 24 16:51:49 volumio volumio[1003]: info: Apr 24 16:51:49 volumio volumio[1003]: ---------------------------- MPD announces system playlist update Apr 24 16:51:49 volumio volumio[1003]: info: Ignoring MPD Status Update Apr 24 16:51:49 volumio sudo[2205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 16:51:49 volumio volumio[1003]: info: ------------------------------ 26ms Apr 24 16:51:49 volumio sudo[2205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 16:51:49 volumio sudo[2205]: pam_unix(sudo:session): session closed for user root Apr 24 16:51:49 volumio flask[555]: /home/volumio/ym_app/venv/lib/python3.5/site-packages/urllib3/connectionpool.py:1004: InsecureRequestWarning: Unverified HTTPS request is being made to host 'api.music.yandex.net'. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings Apr 24 16:51:49 volumio flask[555]: InsecureRequestWarning, Apr 24 16:51:49 volumio flask[555]: /home/volumio/ym_app/venv/lib/python3.5/site-packages/urllib3/connectionpool.py:1004: InsecureRequestWarning: Unverified HTTPS request is being made to host 'storage.mds.yandex.net'. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings Apr 24 16:51:49 volumio flask[555]: InsecureRequestWarning, Apr 24 16:51:49 volumio flask[555]: /home/volumio/ym_app/venv/lib/python3.5/site-packages/urllib3/connectionpool.py:1004: InsecureRequestWarning: Unverified HTTPS request is being made to host 's212iva.storage.yandex.net'. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings Apr 24 16:51:49 volumio flask[555]: InsecureRequestWarning, Apr 24 16:51:49 volumio flask[555]: 127.0.0.1 - - [24/Apr/2024 16:51:49] "GET /stream/ym/album/11037465/track/67269825 HTTP/1.1" 200 - Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::getState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand status Apr 24 16:51:49 volumio volumio[1003]: info: Apr 24 16:51:49 volumio volumio[1003]: ---------------------------- MPD announces state update: player Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::getState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand status Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::getState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand status Apr 24 16:51:49 volumio volumio[1003]: info: Apr 24 16:51:49 volumio volumio[1003]: ---------------------------- MPD announces state update: player Apr 24 16:51:49 volumio volumio[1003]: info: parsing response... Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::getState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand status Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::parseState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: parsing response... Apr 24 16:51:49 volumio volumio[1003]: info: parsing response... Apr 24 16:51:49 volumio volumio[1003]: info: parsing response... Apr 24 16:51:49 volumio volumio[1003]: info: parsing response... Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::parseState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::parseState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::parseState Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 24 16:51:49 volumio volumio[1003]: info: ControllerMpd::parseTrackInfo Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: sending command... Apr 24 16:51:49 volumio volumio[1003]: info: STATE: status=play, position=0, seek=0, duration=159, samplerate=44.1 kHz, bitdepth=24 bit, channels=2, random=false, updatedb=false, repeat=false, isStreaming=false, title=67269825, artist=null, album=null, uri=http://localhost:5100/stream/ym/album/11037465/track/67269825, trackType= Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::syncState Apr 24 16:51:49 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:49 volumio volumio[1003]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"67269825","artist":null,"album":null,"uri":"http://localhost:5100/stream/ym/album/11037465/track/67269825","trackType":""} Apr 24 16:51:49 volumio volumio[1003]: info: CURRENT POSITION 30 Apr 24 16:51:49 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus stop Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::getState Apr 24 16:51:50 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:50 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 16:51:50 volumio volumio[1003]: info: parsing response... Apr 24 16:51:50 volumio volumio[1003]: info: parsing response... Apr 24 16:51:50 volumio volumio[1003]: info: parsing response... Apr 24 16:51:50 volumio volumio[1003]: info: ControllerMpd::parseTrackInfo Apr 24 16:51:50 volumio volumio[1003]: info: ControllerMpd::parseTrackInfo Apr 24 16:51:50 volumio volumio[1003]: info: ControllerMpd::parseTrackInfo Apr 24 16:51:50 volumio volumio[1003]: info: ControllerMpd::pushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::syncState Apr 24 16:51:50 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:50 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ym Received mpd Apr 24 16:51:50 volumio volumio[1003]: info: STATE: status=play, position=0, seek=487, duration=159, samplerate=44.1 kHz, bitdepth=24 bit, channels=2, random=false, updatedb=false, repeat=false, isStreaming=false, title=67269825, artist=null, album=null, uri=http://localhost:5100/stream/ym/album/11037465/track/67269825, trackType= Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::syncState Apr 24 16:51:50 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:50 volumio volumio[1003]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":159,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"67269825","artist":null,"album":null,"uri":"http://localhost:5100/stream/ym/album/11037465/track/67269825","trackType":""} Apr 24 16:51:50 volumio volumio[1003]: info: CURRENT POSITION 30 Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play Apr 24 16:51:50 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::getState Apr 24 16:51:50 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:50 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 16:51:50 volumio sudo[2214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 16:51:50 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::getState Apr 24 16:51:50 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:50 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 16:51:50 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root Apr 24 16:51:50 volumio sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 16:51:50 volumio volumio[1003]: info: ControllerMpd::pushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Apr 24 16:51:50 volumio volumio[1003]: info: CoreStateMachine::syncState Apr 24 16:51:50 volumio volumio[1003]: info: CorePlayQueue::getTrack 30 Apr 24 16:51:50 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ym Received mpd Apr 24 16:51:50 volumio volumio[1003]: info: ------------------------------ 206ms Apr 24 16:51:50 volumio sudo[2219]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 16:51:50 volumio volumio[1003]: info: ------------------------------ 205ms Apr 24 16:51:50 volumio sudo[2219]: pam_unix(sudo:session): session closed for user root Apr 24 16:51:50 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 16:51:50 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 16:51:50 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Apr 24 16:52:05 volumio volumio[1003]: info: CoreCommandRouter::volumioAddQueueItems Apr 24 16:52:05 volumio volumio[1003]: info: CoreStateMachine::addQueueItems Apr 24 16:52:05 volumio volumio[1003]: info: CorePlayQueue::addQueueItems Apr 24 16:52:05 volumio volumio[1003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 16:52:05 volumio volumio[1003]: TypeError: Cannot read property 'startsWith' of undefined Apr 24 16:52:05 volumio volumio[1003]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:143:49) Apr 24 16:52:05 volumio volumio[1003]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:244:24) Apr 24 16:52:05 volumio volumio[1003]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:442:27) Apr 24 16:52:05 volumio volumio[1003]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:66:37) Apr 24 16:52:05 volumio volumio[1003]: at emitTwo (events.js:126:13) Apr 24 16:52:05 volumio volumio[1003]: at Socket.emit (events.js:214:7) Apr 24 16:52:05 volumio volumio[1003]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 24 16:52:05 volumio volumio[1003]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 24 16:52:05 volumio volumio[1003]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 24 16:52:05 volumio volumio[1003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 16:52:06 volumio sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-24 16:51 Apr 24 16:52:06 volumio sudo[2276]: 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="f1b59d96a3893a42f8609371956ecba0438017d7" VOLUMIO_FE_VERSION="95791212f747b95a2cb050c16459a831bfe5c7e7" VOLUMIO_BE_VERSION="1c3c01aeeeb4cff8e6c9a4b6c34e1a558d1b237b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 12 19:57:25 CET 2019" VOLUMIO_VERSION="2.565" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="64b803aae2d00ba86d00d612c4abb38d"