-- Logs begin at Fri 2025-04-25 10:45:46 UTC, end at Fri 2025-12-19 17:13:29 UTC. -- Dec 19 17:12:05 flynn python[949]: 2025-12-19 17:12:05,135 - engineio.client - INFO - Sending packet PING data None Dec 19 17:12:05 flynn python[949]: 2025-12-19 17:12:05,175 - engineio.client - INFO - Received packet PONG data None Dec 19 17:12:08 flynn volumio[947]: info: CoreCommandRouter::volumioGetState Dec 19 17:12:08 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:12:08 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:18 flynn volumio[947]: info: CoreCommandRouter::volumioGetState Dec 19 17:12:18 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:12:18 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:18 flynn volumio[947]: info: Listing playlists Dec 19 17:12:18 flynn volumio[947]: info: Listing playlists Dec 19 17:12:28 flynn volumio[947]: info: CoreCommandRouter::volumioGetState Dec 19 17:12:28 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:12:28 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:30 flynn python[949]: 2025-12-19 17:12:30,141 - engineio.client - INFO - Sending packet PING data None Dec 19 17:12:30 flynn python[949]: 2025-12-19 17:12:30,186 - engineio.client - INFO - Received packet PONG data None Dec 19 17:12:38 flynn volumio[947]: info: CoreCommandRouter::volumioGetState Dec 19 17:12:38 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:12:38 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:38 flynn volumio[947]: info: Listing playlists Dec 19 17:12:38 flynn volumio[947]: info: Listing playlists Dec 19 17:12:48 flynn volumio[947]: info: CoreCommandRouter::volumioGetState Dec 19 17:12:48 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:12:48 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:54 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:54 flynn volumio[947]: info: CorePlayQueue::getTrack 2 Dec 19 17:12:55 flynn python[949]: 2025-12-19 17:12:55,146 - engineio.client - INFO - Sending packet PING data None Dec 19 17:12:55 flynn python[949]: 2025-12-19 17:12:55,161 - engineio.client - INFO - Received packet PONG data None Dec 19 17:12:58 flynn volumio[947]: info: CoreCommandRouter::volumioGetState Dec 19 17:12:58 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:12:58 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:58 flynn volumio[947]: info: Listing playlists Dec 19 17:12:58 flynn volumio[947]: info: Listing playlists Dec 19 17:12:59 flynn volumio[947]: info: Dec 19 17:12:59 flynn volumio[947]: ---------------------------- MPD announces system playlist update Dec 19 17:12:59 flynn volumio[947]: info: Ignoring MPD Status Update Dec 19 17:12:59 flynn volumio[947]: info: Dec 19 17:12:59 flynn volumio[947]: ---------------------------- MPD announces state update: player Dec 19 17:12:59 flynn volumio[947]: info: ControllerMpd::getState Dec 19 17:12:59 flynn volumio[947]: info: ControllerMpd::sendMpdCommand status Dec 19 17:12:59 flynn volumio[947]: info: sending command... Dec 19 17:12:59 flynn volumio[947]: info: Dec 19 17:12:59 flynn volumio[947]: info: ------------------------------ 58ms Dec 19 17:12:59 flynn volumio[947]: info: parsing response... Dec 19 17:12:59 flynn volumio[947]: info: ControllerMpd::parseState Dec 19 17:12:59 flynn volumio[947]: info: ControllerMpd::pushState Dec 19 17:12:59 flynn volumio[947]: info: CoreCommandRouter::servicePushState Dec 19 17:12:59 flynn volumio[947]: info: CoreStateMachine::syncState Dec 19 17:12:59 flynn volumio[947]: info: CoreStateMachine::pushState Dec 19 17:12:59 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:12:59 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:59 flynn volumio[947]: info: CoreCommandRouter::volumioPushState Dec 19 17:12:59 flynn volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:12:59 flynn python[949]: 2025-12-19 17:12:59,864 - 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":"play","volume":35,"mute":false,"artist":"Wizo","track":"Pippi Langstrumpf","albumart":"http://192.168.0.59/albumart?cacheid=980&web=Wizo//extralarge&path=%2FINTERNAL&metadata=false"}}]}] Dec 19 17:12:59 flynn python[949]: 2025-12-19 17:12:59,881 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Dec 19 17:12:59 flynn volumio[947]: info: CorePlayQueue::getTrack 1 Dec 19 17:12:59 flynn volumio[947]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 19 17:12:59 flynn volumio[947]: info: CURRENT POSITION 1 Dec 19 17:12:59 flynn volumio[947]: info: CoreStateMachine::syncState stateService stop Dec 19 17:12:59 flynn volumio[947]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:12:59 flynn volumio[947]: info: CURRENT POSITION 1 Dec 19 17:12:59 flynn volumio[947]: info: CURRENT POSITION 2 Dec 19 17:12:59 flynn volumio[947]: info: END OF QUEUE Dec 19 17:12:59 flynn volumio[947]: info: CoreStateMachine::pushEmptyState Dec 19 17:12:59 flynn volumio[947]: info: CoreCommandRouter::volumioPushState Dec 19 17:12:59 flynn volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:13:00 flynn sudo[4582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:13:00 flynn sudo[4582]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:13:00 flynn volumio[947]: info: CoreStateMachine::stPlaybackTimer Dec 19 17:13:00 flynn python[949]: 2025-12-19 17:13:00,134 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"play","position":1,"title":"Pippi Langstrumpf","artist":"Wizo","album":"","albumart":"/albumart?cacheid=980&web=Wizo//extralarge&path=%2FINTERNAL&metadata=false","uri":"mnt/INTERNAL/Wizo - Pippi Langstrumpf.mp3","trackType":"mp3","seek":136158,"duration":136,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":35,"disableVolumeControl":false,"mute":false,"stream":"mp3","updatedb":false,"volatile":false,"service":"mpd"}] Dec 19 17:13:00 flynn python[949]: 2025-12-19 17:13:00,169 - socketio.client - INFO - Received event "pushState" [/] Dec 19 17:13:00 flynn sudo[4582]: pam_unix(sudo:session): session closed for user root Dec 19 17:13:00 flynn python[949]: 2025-12-19 17:13:00,185 - 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"}}]}] Dec 19 17:13:00 flynn python[949]: 2025-12-19 17:13:00,208 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Dec 19 17:13:00 flynn python[949]: 2025-12-19 17:13:00,244 - 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}] Dec 19 17:13:00 flynn python[949]: 2025-12-19 17:13:00,263 - socketio.client - INFO - Received event "pushState" [/] Dec 19 17:13:00 flynn volumio[947]: info: ------------------------------ 573ms Dec 19 17:13:00 flynn sudo[4588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:13:00 flynn sudo[4588]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:13:00 flynn sudo[4588]: pam_unix(sudo:session): session closed for user root Dec 19 17:13:08 flynn volumio[947]: info: CoreCommandRouter::volumioGetState Dec 19 17:13:08 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:13:08 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:15 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:15 flynn python[949]: !!!! NO CARD !!!! Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,120 - socketio.client - INFO - Emitting event "stop" [/] Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,128 - engineio.client - INFO - Sending packet MESSAGE data 2["stop"] Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,130 - socketio.client - INFO - Emitting event "clearQueue" [/] Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,137 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Dec 19 17:13:15 flynn volumio[947]: info: CoreCommandRouter::volumioStop Dec 19 17:13:15 flynn volumio[947]: info: CoreStateMachine::stop Dec 19 17:13:15 flynn volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:13:15 flynn volumio[947]: UNSET VOLATILE Dec 19 17:13:15 flynn volumio[947]: info: CoreCommandRouter::volumioClearQueue Dec 19 17:13:15 flynn volumio[947]: info: CoreStateMachine::ClearQueue Dec 19 17:13:15 flynn volumio[947]: info: CoreStateMachine::stop Dec 19 17:13:15 flynn volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:13:15 flynn volumio[947]: UNSET VOLATILE Dec 19 17:13:15 flynn volumio[947]: info: CorePlayQueue::clearPlayQueue Dec 19 17:13:15 flynn volumio[947]: info: CorePlayQueue::saveQueue Dec 19 17:13:15 flynn volumio[947]: info: CoreStateMachine::pushEmptyState Dec 19 17:13:15 flynn volumio[947]: info: CoreCommandRouter::volumioPushState Dec 19 17:13:15 flynn volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,296 - 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"}}]}] Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,309 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Dec 19 17:13:15 flynn volumio[947]: info: CoreCommandRouter::volumioPushQueue Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,351 - 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}] Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,370 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,376 - socketio.client - INFO - Received event "pushState" [/] Dec 19 17:13:15 flynn python[949]: 2025-12-19 17:13:15,393 - socketio.client - INFO - Received event "pushQueue" [/] Dec 19 17:13:15 flynn sudo[4631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:13:15 flynn sudo[4631]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:13:15 flynn sudo[4631]: pam_unix(sudo:session): session closed for user root Dec 19 17:13:18 flynn volumio[947]: info: CoreCommandRouter::volumioGetState Dec 19 17:13:18 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:13:18 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:18 flynn volumio[947]: info: Listing playlists Dec 19 17:13:18 flynn volumio[947]: info: Listing playlists Dec 19 17:13:18 flynn python[949]: (53, 1, 1, 1, 1, 1) Dec 19 17:13:18 flynn python[949]: (7, ' --> ', '04096592506481000000', ' --> ', (36420569211930884L,)) Dec 19 17:13:18 flynn python[949]: 2025-12-19 17:13:18,858 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."image" FROM "rfid_card" AS "t1" WHERE ("t1"."id" = ?) LIMIT ? OFFSET ?', [36420569211930884L, 1, 0]) Dec 19 17:13:18 flynn python[949]: 2025-12-19 17:13:18,869 - socketio.client - INFO - Emitting event "clearQueue" [/] Dec 19 17:13:18 flynn python[949]: 2025-12-19 17:13:18,876 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Dec 19 17:13:18 flynn python[949]: 2025-12-19 17:13:18,881 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"rfid": "{'uid': '04096592506481000000', 'uid_int': 36420569211930884L}"}, "type": "rfid"} Dec 19 17:13:18 flynn volumio[947]: info: CoreCommandRouter::volumioClearQueue Dec 19 17:13:18 flynn volumio[947]: info: CoreStateMachine::ClearQueue Dec 19 17:13:18 flynn volumio[947]: info: CoreStateMachine::stop Dec 19 17:13:18 flynn volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:13:18 flynn volumio[947]: UNSET VOLATILE Dec 19 17:13:18 flynn volumio[947]: info: CorePlayQueue::clearPlayQueue Dec 19 17:13:18 flynn volumio[947]: info: CorePlayQueue::saveQueue Dec 19 17:13:18 flynn python[949]: ('!!!! CARD ====> ', {'uid': '04096592506481000000', 'uid_int': 36420569211930884L}, ) Dec 19 17:13:18 flynn python[949]: 2025-12-19 17:13:18,945 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?)', [36420569211930884L]) Dec 19 17:13:19 flynn volumio[947]: info: CoreStateMachine::pushEmptyState Dec 19 17:13:19 flynn volumio[947]: info: CoreCommandRouter::volumioPushState Dec 19 17:13:19 flynn volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:18,995 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36420569211930884L, 1, 0]) Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,051 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['dec961297a5d4bafa87e2f617ec62475']) Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,094 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['dec961297a5d4bafa87e2f617ec62475']) Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,124 - 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"}}]}] Dec 19 17:13:19 flynn volumio[947]: info: CoreCommandRouter::volumioPushQueue Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,141 - socketio.client - INFO - Emitting event "addToQueue" [/] Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,155 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,148 - engineio.client - INFO - Sending packet MESSAGE data 2["addToQueue",{"uri":"music-library/INTERNAL/batman.mp3","service":"mpd"}] Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,194 - 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}] Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,212 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,219 - socketio.client - INFO - Received event "pushState" [/] Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,236 - socketio.client - INFO - Received event "pushQueue" [/] Dec 19 17:13:19 flynn volumio[947]: info: CoreCommandRouter::volumioAddQueueItems Dec 19 17:13:19 flynn volumio[947]: info: CoreStateMachine::addQueueItems Dec 19 17:13:19 flynn volumio[947]: info: CorePlayQueue::addQueueItems Dec 19 17:13:19 flynn volumio[947]: info: Adding Item to queue: music-library/INTERNAL/batman.mp3 Dec 19 17:13:19 flynn volumio[947]: info: Exploding uri music-library/INTERNAL/batman.mp3 in service mpd Dec 19 17:13:19 flynn sudo[4649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:13:19 flynn sudo[4649]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:13:19 flynn volumio[947]: info: ALBUMART /albumart?cacheid=980&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fbatman.mp3&metadata=false Dec 19 17:13:19 flynn volumio[947]: info: URI /mnt/INTERNAL/batman.mp3 Dec 19 17:13:19 flynn volumio[947]: info: CorePlayQueue::saveQueue Dec 19 17:13:19 flynn volumio[947]: info: CoreCommandRouter::volumioPushQueue Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,447 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[{"uri":"mnt/INTERNAL/batman.mp3","service":"mpd","name":"batman.mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=980&web=//extralarge&path=%2FINTERNAL&metadata=false","duration":139,"trackType":"mp3"}]] Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,461 - socketio.client - INFO - Received event "pushQueue" [/] Dec 19 17:13:19 flynn volumio[947]: info: CoreStateMachine::updateTrackBlock Dec 19 17:13:19 flynn sudo[4649]: pam_unix(sudo:session): session closed for user root Dec 19 17:13:19 flynn volumio[947]: info: CorePlayQueue::getTrackBlock Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,499 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Add to queue","message":"music-library/INTERNAL/batman.mp3"}] Dec 19 17:13:19 flynn python[949]: 2025-12-19 17:13:19,525 - socketio.client - INFO - Received event "pushToastMessage" [/] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,164 - engineio.client - INFO - Sending packet PING data None Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,186 - socketio.client - INFO - Emitting event "play" [/] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,192 - engineio.client - INFO - Sending packet MESSAGE data 2["play"] Dec 19 17:13:20 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:20 flynn python[949]: !!!! NO CARD !!!! Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,199 - socketio.client - INFO - Emitting event "stop" [/] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,208 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"song": 0, "songs": [{"uri": "music-library/INTERNAL/batman.mp3", "service": "mpd"}]}, "type": "songs"} Dec 19 17:13:20 flynn volumio[947]: info: CoreCommandRouter::volumioPlay Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,223 - engineio.client - INFO - Received packet PONG data None Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,239 - engineio.client - INFO - Sending packet MESSAGE data 2["stop"] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,241 - socketio.client - INFO - Emitting event "clearQueue" [/] Dec 19 17:13:20 flynn volumio[947]: UNSET VOLATILE Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::play index undefined Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:13:20 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::startPlaybackTimer Dec 19 17:13:20 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,258 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,290 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Play","message":"Started playback of batman.mp3"}] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,299 - socketio.client - INFO - Received event "pushToastMessage" [/] Dec 19 17:13:20 flynn volumio[947]: info: ControllerMpd::clearAddPlayTracks INTERNAL/batman.mp3 Dec 19 17:13:20 flynn volumio[947]: info: ControllerMpd::sendMpdCommand stop Dec 19 17:13:20 flynn volumio[947]: info: sending command... Dec 19 17:13:20 flynn volumio[947]: info: CoreCommandRouter::volumioStop Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::stop Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:13:20 flynn volumio[947]: UNSET VOLATILE Dec 19 17:13:20 flynn volumio[947]: info: CoreCommandRouter::volumioClearQueue Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::ClearQueue Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::stop Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:13:20 flynn volumio[947]: UNSET VOLATILE Dec 19 17:13:20 flynn volumio[947]: info: CorePlayQueue::clearPlayQueue Dec 19 17:13:20 flynn volumio[947]: info: CorePlayQueue::saveQueue Dec 19 17:13:20 flynn volumio[947]: info: CoreStateMachine::pushEmptyState Dec 19 17:13:20 flynn volumio[947]: info: CoreCommandRouter::volumioPushState Dec 19 17:13:20 flynn volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,540 - 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"}}]}] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,559 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Dec 19 17:13:20 flynn volumio[947]: info: CoreCommandRouter::volumioPushQueue Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,588 - 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}] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,604 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,614 - socketio.client - INFO - Received event "pushState" [/] Dec 19 17:13:20 flynn python[949]: 2025-12-19 17:13:20,632 - socketio.client - INFO - Received event "pushQueue" [/] Dec 19 17:13:20 flynn volumio[947]: info: parsing response... Dec 19 17:13:20 flynn volumio[947]: info: ControllerMpd::sendMpdCommand clear Dec 19 17:13:20 flynn volumio[947]: info: sending command... Dec 19 17:13:20 flynn sudo[4667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:13:20 flynn volumio[947]: info: Dec 19 17:13:20 flynn volumio[947]: ---------------------------- MPD announces system playlist update Dec 19 17:13:20 flynn volumio[947]: info: Ignoring MPD Status Update Dec 19 17:13:20 flynn volumio[947]: info: parsing response... Dec 19 17:13:20 flynn volumio[947]: info: ControllerMpd::sendMpdCommand add "INTERNAL/batman.mp3" Dec 19 17:13:20 flynn volumio[947]: info: sending command... Dec 19 17:13:20 flynn volumio[947]: info: Dec 19 17:13:20 flynn volumio[947]: info: Dec 19 17:13:20 flynn volumio[947]: ---------------------------- MPD announces system playlist update Dec 19 17:13:20 flynn sudo[4667]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:13:20 flynn volumio[947]: info: Ignoring MPD Status Update Dec 19 17:13:20 flynn volumio[947]: info: ------------------------------ 78ms Dec 19 17:13:20 flynn volumio[947]: info: parsing response... Dec 19 17:13:20 flynn volumio[947]: info: ControllerMpd::sendMpdCommand play Dec 19 17:13:20 flynn volumio[947]: info: sending command... Dec 19 17:13:20 flynn volumio[947]: info: ------------------------------ 91ms Dec 19 17:13:20 flynn volumio[947]: info: parsing response... Dec 19 17:13:20 flynn sudo[4667]: pam_unix(sudo:session): session closed for user root Dec 19 17:13:21 flynn volumio[947]: info: Dec 19 17:13:21 flynn volumio[947]: ---------------------------- MPD announces state update: player Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::getState Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::sendMpdCommand status Dec 19 17:13:21 flynn volumio[947]: info: sending command... Dec 19 17:13:21 flynn volumio[947]: info: Dec 19 17:13:21 flynn volumio[947]: ---------------------------- MPD announces state update: player Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::getState Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::sendMpdCommand status Dec 19 17:13:21 flynn volumio[947]: info: parsing response... Dec 19 17:13:21 flynn volumio[947]: info: sending command... Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::parseState Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:13:21 flynn volumio[947]: info: sending command... Dec 19 17:13:21 flynn volumio[947]: info: parsing response... Dec 19 17:13:21 flynn volumio[947]: info: parsing response... Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::parseState Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::parseTrackInfo Dec 19 17:13:21 flynn volumio[947]: info: sending command... Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::pushState Dec 19 17:13:21 flynn volumio[947]: info: CoreCommandRouter::servicePushState Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::syncState Dec 19 17:13:21 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:21 flynn volumio[947]: info: STATE SERVICE {"status":"play","position":0,"seek":501,"duration":139,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"batman.mp3","artist":null,"album":null,"uri":"INTERNAL/batman.mp3","trackType":"mp3"} Dec 19 17:13:21 flynn volumio[947]: info: CURRENT POSITION 0 Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::syncState stateService play Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::syncState currentStatus stop Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::pushState Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:13:21 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:21 flynn volumio[947]: info: CoreCommandRouter::volumioPushState Dec 19 17:13:21 flynn volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:13:21 flynn python[949]: 2025-12-19 17:13:21,531 - 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"}}]}] Dec 19 17:13:21 flynn python[949]: 2025-12-19 17:13:21,559 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Dec 19 17:13:21 flynn python[949]: 2025-12-19 17:13:21,601 - 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}] Dec 19 17:13:21 flynn python[949]: 2025-12-19 17:13:21,620 - socketio.client - INFO - Received event "pushState" [/] Dec 19 17:13:21 flynn volumio[947]: info: ------------------------------ 475ms Dec 19 17:13:21 flynn volumio[947]: info: parsing response... Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::parseTrackInfo Dec 19 17:13:21 flynn volumio[947]: info: ControllerMpd::pushState Dec 19 17:13:21 flynn volumio[947]: info: CoreCommandRouter::servicePushState Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::syncState Dec 19 17:13:21 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:21 flynn volumio[947]: info: STATE SERVICE {"status":"play","position":0,"seek":501,"duration":139,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"batman.mp3","artist":null,"album":null,"uri":"INTERNAL/batman.mp3","trackType":"mp3"} Dec 19 17:13:21 flynn volumio[947]: info: CURRENT POSITION 0 Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::syncState stateService play Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:13:21 flynn volumio[947]: info: Received an update from plugin. extracting info from payload Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::pushState Dec 19 17:13:21 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:13:21 flynn sudo[4682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:13:21 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:21 flynn volumio[947]: info: CoreCommandRouter::volumioPushState Dec 19 17:13:21 flynn volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:13:21 flynn sudo[4682]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:13:22 flynn sudo[4682]: pam_unix(sudo:session): session closed for user root Dec 19 17:13:22 flynn python[949]: 2025-12-19 17:13:22,086 - 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"}}]}] Dec 19 17:13:22 flynn python[949]: 2025-12-19 17:13:22,105 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Dec 19 17:13:22 flynn volumio[947]: info: CoreStateMachine::pushState Dec 19 17:13:22 flynn volumio[947]: info: CoreStateMachine::getState Dec 19 17:13:22 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:22 flynn volumio[947]: info: CoreCommandRouter::volumioPushState Dec 19 17:13:22 flynn volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:13:22 flynn sudo[4691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:13:22 flynn python[949]: 2025-12-19 17:13:22,337 - 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}] Dec 19 17:13:22 flynn sudo[4691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:13:22 flynn python[949]: 2025-12-19 17:13:22,364 - socketio.client - INFO - Received event "pushState" [/] Dec 19 17:13:22 flynn python[949]: 2025-12-19 17:13:22,382 - 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"}}]}] Dec 19 17:13:22 flynn python[949]: 2025-12-19 17:13:22,414 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Dec 19 17:13:22 flynn python[949]: 2025-12-19 17:13:22,431 - 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}] Dec 19 17:13:22 flynn python[949]: 2025-12-19 17:13:22,446 - socketio.client - INFO - Received event "pushState" [/] Dec 19 17:13:22 flynn volumio[947]: info: ------------------------------ 1243ms Dec 19 17:13:22 flynn sudo[4691]: pam_unix(sudo:session): session closed for user root Dec 19 17:13:22 flynn sudo[4697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:13:22 flynn sudo[4697]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:13:22 flynn sudo[4697]: pam_unix(sudo:session): session closed for user root Dec 19 17:13:24 flynn python[949]: (53, 1, 0, 1, 1, 0) Dec 19 17:13:24 flynn python[949]: 2025-12-19 17:13:24,104 - lctl - DEBUG - BT 2 Dec 19 17:13:24 flynn python[949]: 2025-12-19 17:13:24,110 - socketio.client - INFO - Emitting event "prev" [/] Dec 19 17:13:24 flynn python[949]: 2025-12-19 17:13:24,112 - engineio.client - INFO - Sending packet MESSAGE data 2["prev"] Dec 19 17:13:24 flynn volumio[947]: info: CoreCommandRouter::volumioPrevious Dec 19 17:13:24 flynn volumio[947]: info: CoreStateMachine::previous Dec 19 17:13:24 flynn volumio[947]: info: CorePlayQueue::getTrack 0 Dec 19 17:13:24 flynn volumio[947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:13:24 flynn volumio[947]: TypeError: Cannot read property 'service' of undefined Dec 19 17:13:24 flynn volumio[947]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1447:105) Dec 19 17:13:24 flynn volumio[947]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:98:27) Dec 19 17:13:24 flynn volumio[947]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:244:31) Dec 19 17:13:24 flynn volumio[947]: at emitNone (events.js:106:13) Dec 19 17:13:24 flynn volumio[947]: at Socket.emit (events.js:208:7) Dec 19 17:13:24 flynn volumio[947]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 19 17:13:24 flynn volumio[947]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Dec 19 17:13:24 flynn volumio[947]: at process._tickCallback (internal/process/next_tick.js:180:9) Dec 19 17:13:24 flynn volumio[947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:13:25 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:25 flynn python[949]: (53, 0, 1, 1, 1, 0) Dec 19 17:13:25 flynn python[949]: 2025-12-19 17:13:25,425 - lctl - DEBUG - BT 1 Dec 19 17:13:25 flynn python[949]: 2025-12-19 17:13:25,436 - socketio.client - INFO - Emitting event "play" [/] Dec 19 17:13:25 flynn python[949]: 2025-12-19 17:13:25,439 - engineio.client - INFO - Sending packet MESSAGE data 2["play"] Dec 19 17:13:25 flynn python[949]: (53, 1, 1, 1, 0, 0) Dec 19 17:13:25 flynn python[949]: 2025-12-19 17:13:25,538 - lctl - DEBUG - BT 4 Dec 19 17:13:25 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:25 flynn python[949]: (53, 1, 1, 1, 0, 0) Dec 19 17:13:25 flynn python[949]: 2025-12-19 17:13:25,859 - lctl - DEBUG - BT 4 Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 0, 0) Dec 19 17:13:26 flynn python[949]: 2025-12-19 17:13:26,066 - lctl - DEBUG - BT 4 Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 0, 0) Dec 19 17:13:26 flynn python[949]: 2025-12-19 17:13:26,274 - lctl - DEBUG - BT 4 Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 0, 0) Dec 19 17:13:26 flynn python[949]: 2025-12-19 17:13:26,481 - lctl - DEBUG - BT 4 Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 0, 0) Dec 19 17:13:26 flynn python[949]: 2025-12-19 17:13:26,745 - lctl - DEBUG - BT 4 Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:26 flynn python[949]: (53, 1, 1, 1, 0, 0) Dec 19 17:13:26 flynn python[949]: 2025-12-19 17:13:26,981 - lctl - DEBUG - BT 4 Dec 19 17:13:27 flynn python[949]: (53, 1, 1, 1, 1, 0) Dec 19 17:13:27 flynn python[949]: (53, 1, 1, 1, 0, 0) Dec 19 17:13:27 flynn python[949]: 2025-12-19 17:13:27,188 - lctl - DEBUG - BT 4 Dec 19 17:13:29 flynn sudo[4730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-19 17:12 Dec 19 17:13:29 flynn sudo[4730]: 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"