-- Logs begin at Fri 2025-04-25 10:45:46 UTC, end at Sat 2025-06-07 17:38:34 UTC. -- Jun 07 17:37:07 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:37:07 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:37:07 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:37:08 flynn volumio[972]: info: Listing playlists Jun 07 17:37:08 flynn volumio[972]: info: Listing playlists Jun 07 17:37:17 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:37:17 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:37:17 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:37:19 flynn python[974]: 2025-06-07 17:37:19,818 - engineio.client - INFO - Sending packet PING data None Jun 07 17:37:19 flynn python[974]: 2025-06-07 17:37:19,856 - engineio.client - INFO - Received packet PONG data None Jun 07 17:37:27 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:37:27 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:37:27 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:37:28 flynn volumio[972]: info: Listing playlists Jun 07 17:37:28 flynn volumio[972]: info: Listing playlists Jun 07 17:37:37 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:37:37 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:37:37 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:37:44 flynn python[974]: 2025-06-07 17:37:44,823 - engineio.client - INFO - Sending packet PING data None Jun 07 17:37:44 flynn python[974]: 2025-06-07 17:37:44,837 - engineio.client - INFO - Received packet PONG data None Jun 07 17:37:47 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:37:47 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:37:47 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:37:48 flynn volumio[972]: info: Listing playlists Jun 07 17:37:48 flynn volumio[972]: info: Listing playlists Jun 07 17:37:57 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:37:58 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:37:58 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:08 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:38:08 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:38:08 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:08 flynn volumio[972]: info: Listing playlists Jun 07 17:38:08 flynn volumio[972]: info: Listing playlists Jun 07 17:38:09 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:09 flynn python[974]: !!!! NO CARD !!!! Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,368 - socketio.client - INFO - Emitting event "stop" [/] Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,377 - engineio.client - INFO - Sending packet MESSAGE data 2["stop"] Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,379 - socketio.client - INFO - Emitting event "clearQueue" [/] Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,382 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Jun 07 17:38:09 flynn volumio[972]: info: CoreCommandRouter::volumioStop Jun 07 17:38:09 flynn volumio[972]: info: CoreStateMachine::stop Jun 07 17:38:09 flynn volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 17:38:09 flynn volumio[972]: UNSET VOLATILE Jun 07 17:38:09 flynn volumio[972]: info: CoreCommandRouter::volumioClearQueue Jun 07 17:38:09 flynn volumio[972]: info: CoreStateMachine::ClearQueue Jun 07 17:38:09 flynn volumio[972]: info: CoreStateMachine::stop Jun 07 17:38:09 flynn volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 17:38:09 flynn volumio[972]: UNSET VOLATILE Jun 07 17:38:09 flynn volumio[972]: info: CorePlayQueue::clearPlayQueue Jun 07 17:38:09 flynn volumio[972]: info: CorePlayQueue::saveQueue Jun 07 17:38:09 flynn volumio[972]: info: CoreStateMachine::pushEmptyState Jun 07 17:38:09 flynn volumio[972]: info: CoreCommandRouter::volumioPushState Jun 07 17:38:09 flynn volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,559 - engineio.client - INFO - Received packet MESSAGE data 2["pushMultiRoomDevices",{"misc":{"debug":true},"list":[{"id":"c9d1955f-9d27-466a-bb01-dbfffee0d89b","host":"http://192.168.0.59","name":"Flynn","isSelf":true,"type":"device","volumeAvailable":true,"state":{"status":"stop","volume":35,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.59/albumart"}}]}] Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,572 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Jun 07 17:38:09 flynn volumio[972]: info: CoreCommandRouter::volumioPushQueue Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,613 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":35,"mute":false,"disableVolumeControl":false,"random":false,"repeat":false,"repeatSingle":false,"updatedb":false,"consume":false}] Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,627 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,634 - socketio.client - INFO - Received event "pushState" [/] Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,656 - socketio.client - INFO - Received event "pushQueue" [/] Jun 07 17:38:09 flynn sudo[6393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:38:09 flynn sudo[6393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,830 - engineio.client - INFO - Sending packet PING data None Jun 07 17:38:09 flynn python[974]: 2025-06-07 17:38:09,843 - engineio.client - INFO - Received packet PONG data None Jun 07 17:38:09 flynn sudo[6393]: pam_unix(sudo:session): session closed for user root Jun 07 17:38:11 flynn python[974]: (50, 1, 1, 1, 1, 1) Jun 07 17:38:11 flynn python[974]: (7, ' --> ', '04469c12041891000000', ' --> ', (40840277394212356L,)) Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,527 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."image" FROM "rfid_card" AS "t1" WHERE ("t1"."id" = ?) LIMIT ? OFFSET ?', [40840277394212356L, 1, 0]) Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,542 - socketio.client - INFO - Emitting event "clearQueue" [/] Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,544 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Jun 07 17:38:11 flynn python[974]: ('!!!! CARD ====> ', {'uid': '04469c12041891000000', 'uid_int': 40840277394212356L}, ) Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,562 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"rfid": "{'uid': '04469c12041891000000', 'uid_int': 40840277394212356L}"}, "type": "rfid"} Jun 07 17:38:11 flynn volumio[972]: info: CoreCommandRouter::volumioClearQueue Jun 07 17:38:11 flynn volumio[972]: info: CoreStateMachine::ClearQueue Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,597 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?)', [40840277394212356L]) Jun 07 17:38:11 flynn volumio[972]: info: CoreStateMachine::stop Jun 07 17:38:11 flynn volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 17:38:11 flynn volumio[972]: UNSET VOLATILE Jun 07 17:38:11 flynn volumio[972]: info: CorePlayQueue::clearPlayQueue Jun 07 17:38:11 flynn volumio[972]: info: CorePlayQueue::saveQueue Jun 07 17:38:11 flynn volumio[972]: info: CoreStateMachine::pushEmptyState Jun 07 17:38:11 flynn volumio[972]: info: CoreCommandRouter::volumioPushState Jun 07 17:38:11 flynn volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,654 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [40840277394212356L, 1, 0]) Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,710 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['3f81245fcf6a4362935acabb90c901c1']) Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,756 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['3f81245fcf6a4362935acabb90c901c1']) Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,780 - socketio.client - INFO - Emitting event "addToQueue" [/] Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,796 - engineio.client - INFO - Sending packet MESSAGE data 2["addToQueue",{"uri":"music-library/INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3","service":"mpd"}] Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,790 - engineio.client - INFO - Received packet MESSAGE data 2["pushMultiRoomDevices",{"misc":{"debug":true},"list":[{"id":"c9d1955f-9d27-466a-bb01-dbfffee0d89b","host":"http://192.168.0.59","name":"Flynn","isSelf":true,"type":"device","volumeAvailable":true,"state":{"status":"stop","volume":35,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.59/albumart"}}]}] Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,817 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Jun 07 17:38:11 flynn volumio[972]: info: CoreCommandRouter::volumioPushQueue Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,903 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":35,"mute":false,"disableVolumeControl":false,"random":false,"repeat":false,"repeatSingle":false,"updatedb":false,"consume":false}] Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,917 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,924 - socketio.client - INFO - Received event "pushState" [/] Jun 07 17:38:11 flynn python[974]: 2025-06-07 17:38:11,946 - socketio.client - INFO - Received event "pushQueue" [/] Jun 07 17:38:11 flynn volumio[972]: info: CoreCommandRouter::volumioAddQueueItems Jun 07 17:38:11 flynn volumio[972]: info: CoreStateMachine::addQueueItems Jun 07 17:38:11 flynn volumio[972]: info: CorePlayQueue::addQueueItems Jun 07 17:38:11 flynn volumio[972]: info: Adding Item to queue: music-library/INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3 Jun 07 17:38:11 flynn sudo[6409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:38:12 flynn volumio[972]: info: Exploding uri music-library/INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3 in service mpd Jun 07 17:38:12 flynn sudo[6409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:38:12 flynn volumio[972]: info: ALBUMART /albumart?cacheid=980&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FJohn%20Williams%20%26%20Vienna%20Philharmonic%20-%20Imperial%20March.mp3&metadata=false Jun 07 17:38:12 flynn volumio[972]: info: URI /mnt/INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3 Jun 07 17:38:12 flynn volumio[972]: info: CorePlayQueue::saveQueue Jun 07 17:38:12 flynn volumio[972]: info: CoreCommandRouter::volumioPushQueue Jun 07 17:38:12 flynn sudo[6409]: pam_unix(sudo:session): session closed for user root Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,128 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[{"uri":"mnt/INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3","service":"mpd","name":"John Williams & Vienna Philharmonic - Imperial March.mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=980&web=//extralarge&path=%2FINTERNAL&metadata=false","duration":229,"trackType":"mp3"}]] Jun 07 17:38:12 flynn volumio[972]: info: CoreStateMachine::updateTrackBlock Jun 07 17:38:12 flynn volumio[972]: info: CorePlayQueue::getTrackBlock Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,158 - socketio.client - INFO - Received event "pushQueue" [/] Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,190 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Add to queue","message":"music-library/INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3"}] Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,206 - socketio.client - INFO - Received event "pushToastMessage" [/] Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,805 - socketio.client - INFO - Emitting event "play" [/] Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,811 - engineio.client - INFO - Sending packet MESSAGE data 2["play"] Jun 07 17:38:12 flynn volumio[972]: info: CoreCommandRouter::volumioPlay Jun 07 17:38:12 flynn volumio[972]: UNSET VOLATILE Jun 07 17:38:12 flynn volumio[972]: info: CoreStateMachine::play index undefined Jun 07 17:38:12 flynn volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 17:38:12 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,831 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"song": 0, "songs": [{"uri": "music-library/INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3", "service": "mpd"}]}, "type": "songs"} Jun 07 17:38:12 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:12 flynn python[974]: !!!! NO CARD !!!! Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,843 - socketio.client - INFO - Emitting event "stop" [/] Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,854 - engineio.client - INFO - Sending packet MESSAGE data 2["stop"] Jun 07 17:38:12 flynn volumio[972]: info: CoreStateMachine::startPlaybackTimer Jun 07 17:38:12 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:12 flynn volumio[972]: info: ControllerMpd::clearAddPlayTracks INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3 Jun 07 17:38:12 flynn volumio[972]: info: ControllerMpd::sendMpdCommand stop Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,867 - socketio.client - INFO - Emitting event "clearQueue" [/] Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,878 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,952 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Play","message":"Started playback of John Williams & Vienna Philharmonic - Imperial March.mp3"}] Jun 07 17:38:12 flynn python[974]: 2025-06-07 17:38:12,968 - socketio.client - INFO - Received event "pushToastMessage" [/] Jun 07 17:38:12 flynn volumio[972]: info: sending command... Jun 07 17:38:13 flynn volumio[972]: info: CoreCommandRouter::volumioStop Jun 07 17:38:13 flynn volumio[972]: info: CoreStateMachine::stop Jun 07 17:38:13 flynn volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 17:38:13 flynn volumio[972]: UNSET VOLATILE Jun 07 17:38:13 flynn volumio[972]: info: CoreCommandRouter::volumioClearQueue Jun 07 17:38:13 flynn volumio[972]: info: CoreStateMachine::ClearQueue Jun 07 17:38:13 flynn volumio[972]: info: CoreStateMachine::stop Jun 07 17:38:13 flynn volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 17:38:13 flynn volumio[972]: UNSET VOLATILE Jun 07 17:38:13 flynn volumio[972]: info: CorePlayQueue::clearPlayQueue Jun 07 17:38:13 flynn volumio[972]: info: CorePlayQueue::saveQueue Jun 07 17:38:13 flynn volumio[972]: info: CoreStateMachine::pushEmptyState Jun 07 17:38:13 flynn volumio[972]: info: CoreCommandRouter::volumioPushState Jun 07 17:38:13 flynn volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:38:13 flynn python[974]: 2025-06-07 17:38:13,197 - engineio.client - INFO - Received packet MESSAGE data 2["pushMultiRoomDevices",{"misc":{"debug":true},"list":[{"id":"c9d1955f-9d27-466a-bb01-dbfffee0d89b","host":"http://192.168.0.59","name":"Flynn","isSelf":true,"type":"device","volumeAvailable":true,"state":{"status":"stop","volume":35,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.59/albumart"}}]}] Jun 07 17:38:13 flynn python[974]: 2025-06-07 17:38:13,213 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Jun 07 17:38:13 flynn volumio[972]: info: CoreCommandRouter::volumioPushQueue Jun 07 17:38:13 flynn python[974]: 2025-06-07 17:38:13,264 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":35,"mute":false,"disableVolumeControl":false,"random":false,"repeat":false,"repeatSingle":false,"updatedb":false,"consume":false}] Jun 07 17:38:13 flynn python[974]: 2025-06-07 17:38:13,283 - socketio.client - INFO - Received event "pushState" [/] Jun 07 17:38:13 flynn python[974]: 2025-06-07 17:38:13,292 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Jun 07 17:38:13 flynn python[974]: 2025-06-07 17:38:13,307 - socketio.client - INFO - Received event "pushQueue" [/] Jun 07 17:38:13 flynn volumio[972]: info: parsing response... Jun 07 17:38:13 flynn sudo[6426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::sendMpdCommand clear Jun 07 17:38:13 flynn volumio[972]: info: sending command... Jun 07 17:38:13 flynn volumio[972]: info: Jun 07 17:38:13 flynn volumio[972]: ---------------------------- MPD announces system playlist update Jun 07 17:38:13 flynn volumio[972]: info: Ignoring MPD Status Update Jun 07 17:38:13 flynn volumio[972]: info: parsing response... Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::sendMpdCommand add "INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3" Jun 07 17:38:13 flynn volumio[972]: info: sending command... Jun 07 17:38:13 flynn sudo[6426]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:38:13 flynn volumio[972]: info: Jun 07 17:38:13 flynn volumio[972]: info: Jun 07 17:38:13 flynn volumio[972]: ---------------------------- MPD announces system playlist update Jun 07 17:38:13 flynn volumio[972]: info: Ignoring MPD Status Update Jun 07 17:38:13 flynn volumio[972]: info: ------------------------------ 80ms Jun 07 17:38:13 flynn volumio[972]: info: parsing response... Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::sendMpdCommand play Jun 07 17:38:13 flynn volumio[972]: info: sending command... Jun 07 17:38:13 flynn sudo[6426]: pam_unix(sudo:session): session closed for user root Jun 07 17:38:13 flynn volumio[972]: info: ------------------------------ 102ms Jun 07 17:38:13 flynn volumio[972]: info: parsing response... Jun 07 17:38:13 flynn volumio[972]: info: Jun 07 17:38:13 flynn volumio[972]: ---------------------------- MPD announces state update: player Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::getState Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::sendMpdCommand status Jun 07 17:38:13 flynn volumio[972]: info: sending command... Jun 07 17:38:13 flynn volumio[972]: info: Jun 07 17:38:13 flynn volumio[972]: ---------------------------- MPD announces state update: player Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::getState Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::sendMpdCommand status Jun 07 17:38:13 flynn volumio[972]: info: parsing response... Jun 07 17:38:13 flynn volumio[972]: info: sending command... Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::parseState Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 07 17:38:13 flynn volumio[972]: info: sending command... Jun 07 17:38:13 flynn volumio[972]: info: parsing response... Jun 07 17:38:13 flynn volumio[972]: info: parsing response... Jun 07 17:38:13 flynn volumio[972]: info: ControllerMpd::parseState Jun 07 17:38:14 flynn volumio[972]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 07 17:38:14 flynn volumio[972]: info: ControllerMpd::parseTrackInfo Jun 07 17:38:14 flynn volumio[972]: info: sending command... Jun 07 17:38:14 flynn volumio[972]: info: ControllerMpd::pushState Jun 07 17:38:14 flynn volumio[972]: info: CoreCommandRouter::servicePushState Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::syncState Jun 07 17:38:14 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:14 flynn volumio[972]: info: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"John Williams & Vienna Philharmonic - Imperial March.mp3","artist":null,"album":null,"uri":"INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3","trackType":"mp3"} Jun 07 17:38:14 flynn volumio[972]: info: CURRENT POSITION 0 Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::syncState stateService play Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::syncState currentStatus stop Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::pushState Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:38:14 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:14 flynn volumio[972]: info: CoreCommandRouter::volumioPushState Jun 07 17:38:14 flynn volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:38:14 flynn python[974]: 2025-06-07 17:38:14,217 - engineio.client - INFO - Received packet MESSAGE data 2["pushMultiRoomDevices",{"misc":{"debug":true},"list":[{"id":"c9d1955f-9d27-466a-bb01-dbfffee0d89b","host":"http://192.168.0.59","name":"Flynn","isSelf":true,"type":"device","volumeAvailable":true,"state":{"status":"stop","volume":35,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.59/albumart"}}]}] Jun 07 17:38:14 flynn python[974]: 2025-06-07 17:38:14,233 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Jun 07 17:38:14 flynn python[974]: 2025-06-07 17:38:14,278 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":35,"mute":false,"disableVolumeControl":false,"random":false,"repeat":false,"repeatSingle":false,"updatedb":false,"consume":false}] Jun 07 17:38:14 flynn python[974]: 2025-06-07 17:38:14,293 - socketio.client - INFO - Received event "pushState" [/] Jun 07 17:38:14 flynn volumio[972]: info: ------------------------------ 467ms Jun 07 17:38:14 flynn volumio[972]: info: parsing response... Jun 07 17:38:14 flynn volumio[972]: info: ControllerMpd::parseTrackInfo Jun 07 17:38:14 flynn volumio[972]: info: ControllerMpd::pushState Jun 07 17:38:14 flynn volumio[972]: info: CoreCommandRouter::servicePushState Jun 07 17:38:14 flynn sudo[6441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::syncState Jun 07 17:38:14 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:14 flynn sudo[6441]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:38:14 flynn volumio[972]: info: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"John Williams & Vienna Philharmonic - Imperial March.mp3","artist":null,"album":null,"uri":"INTERNAL/John Williams & Vienna Philharmonic - Imperial March.mp3","trackType":"mp3"} Jun 07 17:38:14 flynn volumio[972]: info: CURRENT POSITION 0 Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::syncState stateService play Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::syncState currentStatus play Jun 07 17:38:14 flynn volumio[972]: info: Received an update from plugin. extracting info from payload Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::pushState Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:38:14 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:14 flynn volumio[972]: info: CoreCommandRouter::volumioPushState Jun 07 17:38:14 flynn volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:38:14 flynn sudo[6441]: pam_unix(sudo:session): session closed for user root Jun 07 17:38:14 flynn python[974]: 2025-06-07 17:38:14,890 - engineio.client - INFO - Received packet MESSAGE data 2["pushMultiRoomDevices",{"misc":{"debug":true},"list":[{"id":"c9d1955f-9d27-466a-bb01-dbfffee0d89b","host":"http://192.168.0.59","name":"Flynn","isSelf":true,"type":"device","volumeAvailable":true,"state":{"status":"stop","volume":35,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.59/albumart"}}]}] Jun 07 17:38:14 flynn python[974]: 2025-06-07 17:38:14,903 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::pushState Jun 07 17:38:14 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:38:14 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:14 flynn volumio[972]: info: CoreCommandRouter::volumioPushState Jun 07 17:38:14 flynn volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:38:15 flynn sudo[6452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:38:15 flynn python[974]: 2025-06-07 17:38:15,118 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":35,"mute":false,"disableVolumeControl":false,"random":false,"repeat":false,"repeatSingle":false,"updatedb":false,"consume":false}] Jun 07 17:38:15 flynn python[974]: 2025-06-07 17:38:15,151 - socketio.client - INFO - Received event "pushState" [/] Jun 07 17:38:15 flynn sudo[6452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:38:15 flynn python[974]: 2025-06-07 17:38:15,163 - engineio.client - INFO - Received packet MESSAGE data 2["pushMultiRoomDevices",{"misc":{"debug":true},"list":[{"id":"c9d1955f-9d27-466a-bb01-dbfffee0d89b","host":"http://192.168.0.59","name":"Flynn","isSelf":true,"type":"device","volumeAvailable":true,"state":{"status":"stop","volume":35,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.59/albumart"}}]}] Jun 07 17:38:15 flynn volumio[972]: info: ------------------------------ 1338ms Jun 07 17:38:15 flynn python[974]: 2025-06-07 17:38:15,196 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Jun 07 17:38:15 flynn python[974]: 2025-06-07 17:38:15,228 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":35,"mute":false,"disableVolumeControl":false,"random":false,"repeat":false,"repeatSingle":false,"updatedb":false,"consume":false}] Jun 07 17:38:15 flynn python[974]: 2025-06-07 17:38:15,255 - socketio.client - INFO - Received event "pushState" [/] Jun 07 17:38:15 flynn sudo[6452]: pam_unix(sudo:session): session closed for user root Jun 07 17:38:15 flynn sudo[6456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:38:15 flynn sudo[6456]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:38:15 flynn sudo[6456]: pam_unix(sudo:session): session closed for user root Jun 07 17:38:17 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:38:17 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:38:18 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:25 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:25 flynn python[974]: 2025-06-07 17:38:25,871 - lctl - DEBUG - BT 4 Jun 07 17:38:26 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:26 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:26 flynn python[974]: 2025-06-07 17:38:26,419 - lctl - DEBUG - BT 4 Jun 07 17:38:26 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:26 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:26 flynn python[974]: 2025-06-07 17:38:26,739 - lctl - DEBUG - BT 4 Jun 07 17:38:26 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:26 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:26 flynn python[974]: 2025-06-07 17:38:26,975 - lctl - DEBUG - BT 4 Jun 07 17:38:27 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:27 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:27 flynn python[974]: 2025-06-07 17:38:27,239 - lctl - DEBUG - BT 4 Jun 07 17:38:27 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:27 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:27 flynn python[974]: 2025-06-07 17:38:27,532 - lctl - DEBUG - BT 4 Jun 07 17:38:27 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:27 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:27 flynn python[974]: 2025-06-07 17:38:27,824 - lctl - DEBUG - BT 4 Jun 07 17:38:27 flynn volumio[972]: info: CoreCommandRouter::volumioGetState Jun 07 17:38:27 flynn volumio[972]: info: CoreStateMachine::getState Jun 07 17:38:28 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:28 flynn volumio[972]: info: Listing playlists Jun 07 17:38:28 flynn volumio[972]: info: Listing playlists Jun 07 17:38:28 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:28 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:28 flynn python[974]: 2025-06-07 17:38:28,060 - lctl - DEBUG - BT 4 Jun 07 17:38:28 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:28 flynn python[974]: (50, 1, 1, 0, 1, 0) Jun 07 17:38:28 flynn python[974]: 2025-06-07 17:38:28,493 - lctl - DEBUG - BT 3 Jun 07 17:38:29 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:29 flynn python[974]: (50, 1, 0, 1, 1, 0) Jun 07 17:38:29 flynn python[974]: 2025-06-07 17:38:29,069 - lctl - DEBUG - BT 2 Jun 07 17:38:29 flynn python[974]: 2025-06-07 17:38:29,077 - socketio.client - INFO - Emitting event "prev" [/] Jun 07 17:38:29 flynn python[974]: 2025-06-07 17:38:29,079 - engineio.client - INFO - Sending packet MESSAGE data 2["prev"] Jun 07 17:38:29 flynn volumio[972]: info: CoreCommandRouter::volumioPrevious Jun 07 17:38:29 flynn volumio[972]: info: CoreStateMachine::previous Jun 07 17:38:29 flynn volumio[972]: info: CorePlayQueue::getTrack 0 Jun 07 17:38:29 flynn volumio[972]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 17:38:29 flynn volumio[972]: TypeError: Cannot read property 'service' of undefined Jun 07 17:38:29 flynn volumio[972]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1447:105) Jun 07 17:38:29 flynn volumio[972]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:98:27) Jun 07 17:38:29 flynn volumio[972]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:244:31) Jun 07 17:38:29 flynn volumio[972]: at emitNone (events.js:106:13) Jun 07 17:38:29 flynn volumio[972]: at Socket.emit (events.js:208:7) Jun 07 17:38:29 flynn volumio[972]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 07 17:38:29 flynn volumio[972]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jun 07 17:38:29 flynn volumio[972]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 07 17:38:29 flynn volumio[972]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 17:38:30 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:30 flynn python[974]: (50, 0, 1, 1, 1, 0) Jun 07 17:38:30 flynn python[974]: 2025-06-07 17:38:30,837 - lctl - DEBUG - BT 1 Jun 07 17:38:30 flynn python[974]: 2025-06-07 17:38:30,856 - socketio.client - INFO - Emitting event "play" [/] Jun 07 17:38:30 flynn python[974]: 2025-06-07 17:38:30,865 - engineio.client - INFO - Sending packet MESSAGE data 2["play"] Jun 07 17:38:31 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:31 flynn python[974]: (50, 1, 1, 1, 0, 0) Jun 07 17:38:31 flynn python[974]: 2025-06-07 17:38:31,918 - lctl - DEBUG - BT 4 Jun 07 17:38:32 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:32 flynn python[974]: (50, 1, 1, 0, 1, 0) Jun 07 17:38:32 flynn python[974]: 2025-06-07 17:38:32,238 - lctl - DEBUG - BT 3 Jun 07 17:38:32 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:32 flynn python[974]: (50, 1, 1, 0, 1, 0) Jun 07 17:38:32 flynn python[974]: 2025-06-07 17:38:32,418 - lctl - DEBUG - BT 3 Jun 07 17:38:33 flynn python[974]: (50, 1, 1, 1, 1, 0) Jun 07 17:38:33 flynn python[974]: (50, 1, 1, 1, 1, 1) Jun 07 17:38:33 flynn python[974]: (7, ' --> ', '047d9d12041891000000', ' --> ', (40840277394291972L,)) Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,237 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."image" FROM "rfid_card" AS "t1" WHERE ("t1"."id" = ?) LIMIT ? OFFSET ?', [40840277394291972L, 1, 0]) Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,264 - socketio.client - INFO - Emitting event "clearQueue" [/] Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,267 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,277 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"rfid": "{'uid': '047d9d12041891000000', 'uid_int': 40840277394291972L}"}, "type": "rfid"} Jun 07 17:38:33 flynn python[974]: ('!!!! CARD ====> ', {'uid': '047d9d12041891000000', 'uid_int': 40840277394291972L}, ) Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,329 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?)', [40840277394291972L]) Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,378 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [40840277394291972L, 1, 0]) Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,444 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['263d2915831c4bab837a8ded502f5f02']) Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,488 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['263d2915831c4bab837a8ded502f5f02']) Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,503 - socketio.client - INFO - Emitting event "addToQueue" [/] Jun 07 17:38:33 flynn python[974]: 2025-06-07 17:38:33,513 - engineio.client - INFO - Sending packet MESSAGE data 2["addToQueue",{"uri":"music-library/INTERNAL/John Williams & Vienna Philharmonic \u2013 Williams\uff1a Theme from \u201cJurassic Park\u201d [-NqaupGcCpw].mp3","service":"mpd"}] Jun 07 17:38:34 flynn sudo[6528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-07 17:37 Jun 07 17:38:34 flynn sudo[6528]: 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="ba22215fabbe766d668749499a1c25fcefd90f0a" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="043dcaee624f27d121213b83af11a9f2feac0945" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Mar 18 01:32:43 CET 2020" VOLUMIO_VERSION="2.729" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="5a0b29326faa8f5d0f5fe95d9e58c069"