-- Logs begin at Fri 2021-12-10 15:22:34 UTC, end at Tue 2024-04-16 16:17:41 UTC. -- Apr 16 16:16:15 flynn python[963]: 2024-04-16 16:16:15,407 - engineio.client - INFO - Sending packet PING data None Apr 16 16:16:15 flynn python[963]: 2024-04-16 16:16:15,428 - engineio.client - INFO - Received packet PONG data None Apr 16 16:16:40 flynn python[963]: 2024-04-16 16:16:40,413 - engineio.client - INFO - Sending packet PING data None Apr 16 16:16:41 flynn python[963]: 2024-04-16 16:16:41,350 - engineio.client - INFO - Received packet PONG data None Apr 16 16:17:03 flynn volumio[961]: info: CorePlayQueue::getTrack 0 Apr 16 16:17:03 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:03 flynn volumio[961]: info: Prefetching next song Apr 16 16:17:03 flynn volumio[961]: info: DOING PREFETCH IN MPD Apr 16 16:17:03 flynn volumio[961]: info: ControllerMpd::sendMpdCommand add "INTERNAL/Wizo - Pippi Langstrumpf.mp3" Apr 16 16:17:03 flynn volumio[961]: info: sending command... Apr 16 16:17:03 flynn volumio[961]: info: Apr 16 16:17:03 flynn volumio[961]: ---------------------------- MPD announces system playlist update Apr 16 16:17:03 flynn volumio[961]: info: Ignoring MPD Status Update Apr 16 16:17:03 flynn volumio[961]: info: parsing response... Apr 16 16:17:03 flynn volumio[961]: info: ControllerMpd::sendMpdCommand consume 1 Apr 16 16:17:03 flynn volumio[961]: info: sending command... Apr 16 16:17:03 flynn volumio[961]: info: Apr 16 16:17:03 flynn volumio[961]: ---------------------------- MPD announces state update: options Apr 16 16:17:03 flynn volumio[961]: info: ------------------------------ 95ms Apr 16 16:17:03 flynn volumio[961]: info: parsing response... Apr 16 16:17:03 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:03 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:03 flynn volumio[961]: info: sending command... Apr 16 16:17:03 flynn volumio[961]: info: parsing response... Apr 16 16:17:03 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:03 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:03 flynn volumio[961]: info: sending command... Apr 16 16:17:03 flynn volumio[961]: info: parsing response... Apr 16 16:17:03 flynn volumio[961]: info: ControllerMpd::parseTrackInfo Apr 16 16:17:03 flynn volumio[961]: info: ControllerMpd::pushState Apr 16 16:17:03 flynn volumio[961]: info: CoreCommandRouter::servicePushState Apr 16 16:17:03 flynn volumio[961]: info: CoreStateMachine::syncState Apr 16 16:17:03 flynn volumio[961]: info: ERROR Prefetch 500ms setTimeout missed >> directly calling increasePlaybackTimer Apr 16 16:17:03 flynn volumio[961]: info: ERROR this.runPlaybackTimer:true this.currentSongDuration:81000 - this.currentSeek:76226 - this.prefetchDone:true - this.simulateStopStartDone:false Apr 16 16:17:03 flynn volumio[961]: info: CoreStateMachine::startPlaybackTimer Apr 16 16:17:03 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:03 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:03 flynn volumio[961]: info: STATE SERVICE {"status":"play","position":0,"seek":76490,"duration":81,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","artist":null,"album":null,"uri":"INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","trackType":"mp3"} Apr 16 16:17:03 flynn volumio[961]: info: CURRENT POSITION 1 Apr 16 16:17:03 flynn volumio[961]: info: CoreStateMachine::syncState stateService play Apr 16 16:17:03 flynn volumio[961]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:17:03 flynn volumio[961]: info: Received an update from plugin. extracting info from payload Apr 16 16:17:03 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:03 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:03 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:03 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:03 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:03 flynn python[963]: 2024-04-16 16:17:03,592 - 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"}}]}] Apr 16 16:17:03 flynn python[963]: 2024-04-16 16:17:03,612 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:03 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:03 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:03 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:03 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:03 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:03 flynn sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:03 flynn sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:03 flynn python[963]: 2024-04-16 16:17:03,872 - 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":76490,"duration":81,"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"}] Apr 16 16:17:03 flynn python[963]: 2024-04-16 16:17:03,903 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:03 flynn python[963]: 2024-04-16 16:17:03,924 - 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"}}]}] Apr 16 16:17:03 flynn sudo[1551]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:03 flynn python[963]: 2024-04-16 16:17:03,943 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:03 flynn python[963]: 2024-04-16 16:17:03,987 - 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":76490,"duration":81,"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"}] Apr 16 16:17:04 flynn python[963]: 2024-04-16 16:17:04,012 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:04 flynn volumio[961]: info: ------------------------------ 746ms Apr 16 16:17:04 flynn sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:04 flynn sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:04 flynn sudo[1557]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:05 flynn python[963]: 2024-04-16 16:17:05,426 - engineio.client - INFO - Sending packet PING data None Apr 16 16:17:05 flynn python[963]: 2024-04-16 16:17:05,450 - engineio.client - INFO - Received packet PONG data None Apr 16 16:17:07 flynn volumio[961]: info: Apr 16 16:17:07 flynn volumio[961]: ---------------------------- MPD announces system playlist update Apr 16 16:17:07 flynn volumio[961]: info: Ignoring MPD Status Update Apr 16 16:17:07 flynn volumio[961]: info: Apr 16 16:17:07 flynn volumio[961]: ---------------------------- MPD announces state update: player Apr 16 16:17:07 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:07 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:07 flynn volumio[961]: info: sending command... Apr 16 16:17:07 flynn volumio[961]: info: ------------------------------ 50ms Apr 16 16:17:08 flynn volumio[961]: info: Apr 16 16:17:08 flynn volumio[961]: ---------------------------- MPD announces state update: player Apr 16 16:17:08 flynn volumio[961]: info: parsing response... Apr 16 16:17:08 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:08 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:08 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:08 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:08 flynn volumio[961]: info: sending command... Apr 16 16:17:08 flynn volumio[961]: info: sending command... Apr 16 16:17:08 flynn volumio[961]: info: parsing response... Apr 16 16:17:08 flynn volumio[961]: info: parsing response... Apr 16 16:17:08 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:08 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:08 flynn volumio[961]: info: ControllerMpd::parseTrackInfo Apr 16 16:17:08 flynn volumio[961]: info: sending command... Apr 16 16:17:08 flynn volumio[961]: info: ControllerMpd::pushState Apr 16 16:17:08 flynn volumio[961]: info: CoreCommandRouter::servicePushState Apr 16 16:17:08 flynn volumio[961]: info: CoreStateMachine::syncState Apr 16 16:17:08 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:08 flynn volumio[961]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pippi Langstrumpf","artist":"Wizo","album":null,"uri":"INTERNAL/Wizo - Pippi Langstrumpf.mp3","trackType":"mp3"} Apr 16 16:17:08 flynn volumio[961]: info: CURRENT POSITION 1 Apr 16 16:17:08 flynn volumio[961]: info: CoreStateMachine::syncState stateService play Apr 16 16:17:08 flynn volumio[961]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:17:08 flynn volumio[961]: info: Received an update from plugin. extracting info from payload Apr 16 16:17:08 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:08 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:08 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:08 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:08 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:08 flynn python[963]: 2024-04-16 16:17:08,561 - 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"}}]}] Apr 16 16:17:08 flynn python[963]: 2024-04-16 16:17:08,580 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:08 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:08 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:08 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:08 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:08 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:08 flynn sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:08 flynn sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:08 flynn python[963]: 2024-04-16 16:17:08,800 - 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":0,"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"}] Apr 16 16:17:08 flynn python[963]: 2024-04-16 16:17:08,838 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:08 flynn python[963]: 2024-04-16 16:17:08,857 - 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"}}]}] Apr 16 16:17:08 flynn python[963]: 2024-04-16 16:17:08,877 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:08 flynn sudo[1581]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:08 flynn python[963]: 2024-04-16 16:17:08,907 - 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":0,"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"}] Apr 16 16:17:08 flynn volumio[961]: info: ------------------------------ 1207ms Apr 16 16:17:08 flynn python[963]: 2024-04-16 16:17:08,940 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:09 flynn volumio[961]: info: parsing response... Apr 16 16:17:09 flynn volumio[961]: info: ControllerMpd::parseTrackInfo Apr 16 16:17:09 flynn volumio[961]: info: ControllerMpd::pushState Apr 16 16:17:09 flynn volumio[961]: info: CoreCommandRouter::servicePushState Apr 16 16:17:09 flynn volumio[961]: info: CoreStateMachine::syncState Apr 16 16:17:09 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:09 flynn volumio[961]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pippi Langstrumpf","artist":"Wizo","album":null,"uri":"INTERNAL/Wizo - Pippi Langstrumpf.mp3","trackType":"mp3"} Apr 16 16:17:09 flynn volumio[961]: info: CURRENT POSITION 1 Apr 16 16:17:09 flynn volumio[961]: info: CoreStateMachine::syncState stateService play Apr 16 16:17:09 flynn sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:09 flynn volumio[961]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:17:09 flynn volumio[961]: info: Received an update from plugin. extracting info from payload Apr 16 16:17:09 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:09 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:09 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:09 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:09 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:09 flynn sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:09 flynn python[963]: 2024-04-16 16:17:09,303 - 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"}}]}] Apr 16 16:17:09 flynn python[963]: 2024-04-16 16:17:09,323 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:09 flynn sudo[1586]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:09 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:09 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:09 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:09 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:09 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:09 flynn sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:09 flynn sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:09 flynn python[963]: 2024-04-16 16:17:09,571 - 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":487,"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"}] Apr 16 16:17:09 flynn python[963]: 2024-04-16 16:17:09,618 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:09 flynn python[963]: 2024-04-16 16:17:09,649 - 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"}}]}] Apr 16 16:17:09 flynn python[963]: 2024-04-16 16:17:09,673 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:09 flynn python[963]: 2024-04-16 16:17:09,701 - 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":487,"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"}] Apr 16 16:17:09 flynn sudo[1600]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:09 flynn python[963]: 2024-04-16 16:17:09,714 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:09 flynn volumio[961]: info: ------------------------------ 1418ms Apr 16 16:17:09 flynn sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:09 flynn sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:10 flynn sudo[1607]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:15 flynn python[963]: (56, 1, 1, 1, 1, 0) Apr 16 16:17:15 flynn python[963]: !!!! NO CARD !!!! Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,226 - socketio.client - INFO - Emitting event "stop" [/] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,235 - engineio.client - INFO - Sending packet MESSAGE data 2["stop"] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,237 - socketio.client - INFO - Emitting event "clearQueue" [/] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,244 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Apr 16 16:17:15 flynn volumio[961]: info: CoreCommandRouter::volumioStop Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:15 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::stPlaybackTimer Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::updateTrackBlock Apr 16 16:17:15 flynn volumio[961]: info: CorePlayQueue::getTrackBlock Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:15 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:15 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:15 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,434 - 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":"Wizo","track":"Pippi Langstrumpf","albumart":"http://192.168.0.59/albumart?cacheid=980&web=Wizo//extralarge&path=%2FINTERNAL&metadata=false"}}]}] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,446 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::serviceStop Apr 16 16:17:15 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:15 flynn volumio[961]: info: CoreCommandRouter::serviceStop Apr 16 16:17:15 flynn volumio[961]: info: ControllerMpd::stop Apr 16 16:17:15 flynn volumio[961]: info: ControllerMpd::sendMpdCommand stop Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,539 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"stop","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":0,"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"}] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,562 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:15 flynn volumio[961]: info: sending command... Apr 16 16:17:15 flynn volumio[961]: info: CoreCommandRouter::volumioClearQueue Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::ClearQueue Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:15 flynn sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:15 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:15 flynn volumio[961]: info: CorePlayQueue::clearPlayQueue Apr 16 16:17:15 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:15 flynn volumio[961]: info: CoreStateMachine::pushEmptyState Apr 16 16:17:15 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:15 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:15 flynn sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:15 flynn sudo[1636]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,817 - 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"}}]}] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,832 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:15 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,911 - 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}] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,931 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,925 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Apr 16 16:17:15 flynn python[963]: 2024-04-16 16:17:15,953 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:16 flynn sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:16 flynn volumio[961]: info: Apr 16 16:17:16 flynn volumio[961]: ---------------------------- MPD announces state update: player Apr 16 16:17:16 flynn volumio[961]: info: parsing response... Apr 16 16:17:16 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:16 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:16 flynn sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:16 flynn volumio[961]: info: sending command... Apr 16 16:17:16 flynn volumio[961]: info: parsing response... Apr 16 16:17:16 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:16 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:16 flynn volumio[961]: info: sending command... Apr 16 16:17:16 flynn sudo[1646]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:16 flynn volumio[961]: info: parsing response... Apr 16 16:17:16 flynn volumio[961]: info: ControllerMpd::parseTrackInfo Apr 16 16:17:16 flynn volumio[961]: info: ControllerMpd::pushState Apr 16 16:17:16 flynn volumio[961]: info: CoreCommandRouter::servicePushState Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::syncState Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:16 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:16 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:16 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:16 flynn python[963]: 2024-04-16 16:17:16,415 - 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"}}]}] Apr 16 16:17:16 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:16 flynn python[963]: 2024-04-16 16:17:16,428 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:16 flynn volumio[961]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pippi Langstrumpf","artist":"Wizo","album":null,"uri":"INTERNAL/Wizo - Pippi Langstrumpf.mp3","trackType":"mp3"} Apr 16 16:17:16 flynn volumio[961]: info: CURRENT POSITION 1 Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::syncState stateService stop Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:16 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:16 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:16 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:16 flynn sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:16 flynn sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:16 flynn volumio[961]: info: No code Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:16 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:16 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:16 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:16 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:16 flynn sudo[1661]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:16 flynn sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:16 flynn python[963]: 2024-04-16 16:17:16,871 - 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}] Apr 16 16:17:16 flynn python[963]: 2024-04-16 16:17:16,905 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:16 flynn python[963]: 2024-04-16 16:17:16,926 - 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"}}]}] Apr 16 16:17:16 flynn python[963]: 2024-04-16 16:17:16,948 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:16 flynn sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:16 flynn python[963]: 2024-04-16 16:17:16,978 - 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}] Apr 16 16:17:17 flynn python[963]: 2024-04-16 16:17:16,992 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:17 flynn python[963]: 2024-04-16 16:17:17,020 - 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"}}]}] Apr 16 16:17:17 flynn python[963]: 2024-04-16 16:17:17,047 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:17 flynn python[963]: 2024-04-16 16:17:17,064 - 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}] Apr 16 16:17:17 flynn python[963]: 2024-04-16 16:17:17,077 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:17 flynn sudo[1666]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:17 flynn volumio[961]: info: ------------------------------ 1012ms Apr 16 16:17:17 flynn sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:17 flynn sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:17 flynn sudo[1673]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:23 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:23 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,632 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."image" FROM "rfid_card" AS "t1" WHERE ("t1"."id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,649 - socketio.client - INFO - Emitting event "clearQueue" [/] Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,652 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Apr 16 16:17:23 flynn python[963]: ('!!!! CARD ====> ', {'uid': '04206492506480000000', 'uid_int': 36139094235160580L}, ) Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,674 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?)', [36139094235160580L]) Apr 16 16:17:23 flynn volumio[961]: info: CoreCommandRouter::volumioClearQueue Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,694 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"rfid": "{'uid': '04206492506480000000', 'uid_int': 36139094235160580L}"}, "type": "rfid"} Apr 16 16:17:23 flynn volumio[961]: info: CoreStateMachine::ClearQueue Apr 16 16:17:23 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:23 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:23 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:23 flynn volumio[961]: info: CorePlayQueue::clearPlayQueue Apr 16 16:17:23 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:23 flynn volumio[961]: info: CoreStateMachine::pushEmptyState Apr 16 16:17:23 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:23 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,794 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,858 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,918 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,930 - 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"}}]}] Apr 16 16:17:23 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:23 flynn python[963]: 2024-04-16 16:17:23,968 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:23,997 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,031 - 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}] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,061 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,072 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,104 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,110 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,132 - socketio.client - INFO - Emitting event "addToQueue" [/] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,141 - engineio.client - INFO - Sending packet MESSAGE data 2["addToQueue",[{"uri":"music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","service":"mpd"},{"uri":"music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3","service":"mpd"}]] Apr 16 16:17:24 flynn sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:24 flynn volumio[961]: info: CoreCommandRouter::volumioAddQueueItems Apr 16 16:17:24 flynn volumio[961]: info: CoreStateMachine::addQueueItems Apr 16 16:17:24 flynn volumio[961]: info: CorePlayQueue::addQueueItems Apr 16 16:17:24 flynn volumio[961]: info: Adding Item to queue: music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 Apr 16 16:17:24 flynn volumio[961]: info: Exploding uri music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 in service mpd Apr 16 16:17:24 flynn sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:24 flynn volumio[961]: info: Adding Item to queue: music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:24 flynn volumio[961]: info: Exploding uri music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3 in service mpd Apr 16 16:17:24 flynn volumio[961]: info: ALBUMART /albumart?cacheid=980&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FPippi%20Langstrumpf%20-%20Intro%20%5B1971%5D%20%5BlPOdvUUw6dc%5D.mp3&metadata=false Apr 16 16:17:24 flynn volumio[961]: info: URI /mnt/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 Apr 16 16:17:24 flynn sudo[1706]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:24 flynn volumio[961]: info: ALBUMART /albumart?cacheid=980&web=Wizo//extralarge&path=%2Fmnt%2FINTERNAL%2FWizo%20-%20Pippi%20Langstrumpf.mp3&metadata=false Apr 16 16:17:24 flynn volumio[961]: info: URI /mnt/INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:24 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:24 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:24 flynn volumio[961]: info: CoreStateMachine::updateTrackBlock Apr 16 16:17:24 flynn volumio[961]: info: CorePlayQueue::getTrackBlock Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,427 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[{"uri":"mnt/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","service":"mpd","name":"Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=980&web=//extralarge&path=%2FINTERNAL&metadata=false","duration":81,"trackType":"mp3"},{"uri":"mnt/INTERNAL/Wizo - Pippi Langstrumpf.mp3","service":"mpd","name":"Pippi Langstrumpf","artist":"Wizo","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=980&web=Wizo//extralarge&path=%2FINTERNAL&metadata=false","duration":136,"trackType":"mp3"}]] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,447 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,462 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Add to queue"}] Apr 16 16:17:24 flynn python[963]: 2024-04-16 16:17:24,487 - socketio.client - INFO - Received event "pushToastMessage" [/] Apr 16 16:17:25 flynn python[963]: 2024-04-16 16:17:25,150 - socketio.client - INFO - Emitting event "play" [/] Apr 16 16:17:25 flynn python[963]: 2024-04-16 16:17:25,156 - engineio.client - INFO - Sending packet MESSAGE data 2["play"] Apr 16 16:17:25 flynn volumio[961]: info: CoreCommandRouter::volumioPlay Apr 16 16:17:25 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:25 flynn volumio[961]: info: CoreStateMachine::play index undefined Apr 16 16:17:25 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:25 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:25 flynn volumio[961]: info: CoreStateMachine::startPlaybackTimer Apr 16 16:17:25 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::clearAddPlayTracks INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::sendMpdCommand stop Apr 16 16:17:25 flynn python[963]: 2024-04-16 16:17:25,249 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Play","message":"Started playback of Pippi Langstrumpf"}] Apr 16 16:17:25 flynn python[963]: 2024-04-16 16:17:25,260 - socketio.client - INFO - Received event "pushToastMessage" [/] Apr 16 16:17:25 flynn volumio[961]: info: sending command... Apr 16 16:17:25 flynn volumio[961]: info: parsing response... Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::sendMpdCommand clear Apr 16 16:17:25 flynn volumio[961]: info: sending command... Apr 16 16:17:25 flynn volumio[961]: info: Apr 16 16:17:25 flynn volumio[961]: ---------------------------- MPD announces system playlist update Apr 16 16:17:25 flynn volumio[961]: info: Ignoring MPD Status Update Apr 16 16:17:25 flynn volumio[961]: info: parsing response... Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::sendMpdCommand add "INTERNAL/Wizo - Pippi Langstrumpf.mp3" Apr 16 16:17:25 flynn volumio[961]: info: sending command... Apr 16 16:17:25 flynn volumio[961]: info: Apr 16 16:17:25 flynn volumio[961]: info: Apr 16 16:17:25 flynn volumio[961]: ---------------------------- MPD announces system playlist update Apr 16 16:17:25 flynn volumio[961]: info: Ignoring MPD Status Update Apr 16 16:17:25 flynn volumio[961]: info: ------------------------------ 63ms Apr 16 16:17:25 flynn volumio[961]: info: parsing response... Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::sendMpdCommand play Apr 16 16:17:25 flynn volumio[961]: info: sending command... Apr 16 16:17:25 flynn volumio[961]: info: ------------------------------ 62ms Apr 16 16:17:25 flynn volumio[961]: info: parsing response... Apr 16 16:17:25 flynn volumio[961]: info: Apr 16 16:17:25 flynn volumio[961]: ---------------------------- MPD announces state update: player Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:25 flynn volumio[961]: info: sending command... Apr 16 16:17:25 flynn volumio[961]: info: Apr 16 16:17:25 flynn volumio[961]: ---------------------------- MPD announces state update: player Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:25 flynn volumio[961]: info: sending command... Apr 16 16:17:25 flynn volumio[961]: info: parsing response... Apr 16 16:17:25 flynn volumio[961]: info: parsing response... Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:25 flynn volumio[961]: info: sending command... Apr 16 16:17:25 flynn volumio[961]: info: sending command... Apr 16 16:17:25 flynn volumio[961]: info: parsing response... Apr 16 16:17:25 flynn volumio[961]: info: parsing response... Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::parseTrackInfo Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::parseTrackInfo Apr 16 16:17:25 flynn volumio[961]: info: ControllerMpd::pushState Apr 16 16:17:25 flynn volumio[961]: info: CoreCommandRouter::servicePushState Apr 16 16:17:25 flynn volumio[961]: info: CoreStateMachine::syncState Apr 16 16:17:25 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:25 flynn volumio[961]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pippi Langstrumpf","artist":"Wizo","album":null,"uri":"INTERNAL/Wizo - Pippi Langstrumpf.mp3","trackType":"mp3"} Apr 16 16:17:25 flynn volumio[961]: info: CURRENT POSITION 1 Apr 16 16:17:25 flynn volumio[961]: info: CoreStateMachine::syncState stateService play Apr 16 16:17:25 flynn volumio[961]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:17:25 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:25 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:25 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:25 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:25 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,019 - 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"}}]}] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,037 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:26 flynn volumio[961]: info: ControllerMpd::pushState Apr 16 16:17:26 flynn volumio[961]: info: CoreCommandRouter::servicePushState Apr 16 16:17:26 flynn volumio[961]: info: CoreStateMachine::syncState Apr 16 16:17:26 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:26 flynn volumio[961]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pippi Langstrumpf","artist":"Wizo","album":null,"uri":"INTERNAL/Wizo - Pippi Langstrumpf.mp3","trackType":"mp3"} Apr 16 16:17:26 flynn volumio[961]: info: CURRENT POSITION 1 Apr 16 16:17:26 flynn volumio[961]: info: CoreStateMachine::syncState stateService play Apr 16 16:17:26 flynn volumio[961]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:17:26 flynn volumio[961]: info: Received an update from plugin. extracting info from payload Apr 16 16:17:26 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:26 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:26 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:26 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:26 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:26 flynn sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:26 flynn sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:26 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:26 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:26 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:26 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:26 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:26 flynn sudo[1721]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:26 flynn python[963]: (56, 1, 1, 1, 1, 0) Apr 16 16:17:26 flynn python[963]: !!!! NO CARD !!!! Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,511 - socketio.client - INFO - Emitting event "stop" [/] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,532 - engineio.client - INFO - Sending packet MESSAGE data 2["stop"] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,540 - socketio.client - INFO - Emitting event "clearQueue" [/] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,556 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Apr 16 16:17:26 flynn sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,725 - 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":487,"duration":136,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":35,"disableVolumeControl":false,"mute":false,"stream":"mp3","updatedb":false,"volatile":false,"service":"mpd"}] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,768 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:26 flynn sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,798 - 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"}}]}] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,825 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,853 - 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":487,"duration":136,"samplerate":"44.1 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"}] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,884 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,910 - 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"}}]}] Apr 16 16:17:26 flynn sudo[1727]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:26 flynn python[963]: 2024-04-16 16:17:26,955 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:26 flynn python[963]: Exception in thread Thread-71: Apr 16 16:17:26 flynn python[963]: Traceback (most recent call last): Apr 16 16:17:27 flynn python[963]: File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner Apr 16 16:17:27 flynn python[963]: self.run() Apr 16 16:17:27 flynn python[963]: File "/usr/lib/python2.7/threading.py", line 763, in run Apr 16 16:17:27 flynn python[963]: self.__target(*self.__args, **self.__kwargs) Apr 16 16:17:27 flynn python[963]: File "/usr/local/lib/python2.7/dist-packages/socketio/client.py", line 592, in _handle_eio_message Apr 16 16:17:27 flynn python[963]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Apr 16 16:17:27 flynn python[963]: File "/usr/local/lib/python2.7/dist-packages/socketio/client.py", line 476, in _handle_event Apr 16 16:17:27 flynn python[963]: r = self._trigger_event(data[0], namespace, *data[1:]) Apr 16 16:17:27 flynn python[963]: File "/usr/local/lib/python2.7/dist-packages/socketio/client.py", line 526, in _trigger_event Apr 16 16:17:27 flynn python[963]: return self.handlers[namespace][event](*args) Apr 16 16:17:27 flynn python[963]: File "lctrl/main.py", line 176, in on_message Apr 16 16:17:27 flynn python[963]: self.current_queue[msg["position"]].update(msg) Apr 16 16:17:27 flynn python[963]: IndexError: list index out of range Apr 16 16:17:27 flynn python[963]: 2024-04-16 16:17:27,009 - 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":487,"duration":136,"samplerate":"44.1 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"}] Apr 16 16:17:27 flynn python[963]: 2024-04-16 16:17:27,036 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:27 flynn python[963]: Exception in thread Thread-73: Apr 16 16:17:27 flynn python[963]: Traceback (most recent call last): Apr 16 16:17:27 flynn python[963]: File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner Apr 16 16:17:27 flynn python[963]: self.run() Apr 16 16:17:27 flynn python[963]: File "/usr/lib/python2.7/threading.py", line 763, in run Apr 16 16:17:27 flynn python[963]: self.__target(*self.__args, **self.__kwargs) Apr 16 16:17:27 flynn python[963]: File "/usr/local/lib/python2.7/dist-packages/socketio/client.py", line 592, in _handle_eio_message Apr 16 16:17:27 flynn python[963]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Apr 16 16:17:27 flynn python[963]: File "/usr/local/lib/python2.7/dist-packages/socketio/client.py", line 476, in _handle_event Apr 16 16:17:27 flynn python[963]: r = self._trigger_event(data[0], namespace, *data[1:]) Apr 16 16:17:27 flynn python[963]: File "/usr/local/lib/python2.7/dist-packages/socketio/client.py", line 526, in _trigger_event Apr 16 16:17:27 flynn python[963]: return self.handlers[namespace][event](*args) Apr 16 16:17:27 flynn python[963]: File "lctrl/main.py", line 176, in on_message Apr 16 16:17:27 flynn python[963]: self.current_queue[msg["position"]].update(msg) Apr 16 16:17:27 flynn python[963]: IndexError: list index out of range Apr 16 16:17:27 flynn sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:27 flynn sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:27 flynn volumio[961]: info: ------------------------------ 1684ms Apr 16 16:17:27 flynn volumio[961]: info: ------------------------------ 1627ms Apr 16 16:17:27 flynn sudo[1733]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:27 flynn volumio[961]: info: CoreCommandRouter::volumioStop Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:27 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::stPlaybackTimer Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::updateTrackBlock Apr 16 16:17:27 flynn volumio[961]: info: CorePlayQueue::getTrackBlock Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:27 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:27 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:27 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:27 flynn python[963]: 2024-04-16 16:17:27,612 - 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":"Wizo","track":"Pippi Langstrumpf","albumart":"http://192.168.0.59/albumart?cacheid=980&web=Wizo//extralarge&path=%2FINTERNAL&metadata=false"}}]}] Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::serviceStop Apr 16 16:17:27 flynn python[963]: 2024-04-16 16:17:27,626 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:27 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:27 flynn volumio[961]: info: CoreCommandRouter::serviceStop Apr 16 16:17:27 flynn volumio[961]: info: ControllerMpd::stop Apr 16 16:17:27 flynn volumio[961]: info: ControllerMpd::sendMpdCommand stop Apr 16 16:17:27 flynn volumio[961]: info: CoreCommandRouter::volumioClearQueue Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::ClearQueue Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:27 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:27 flynn volumio[961]: info: CorePlayQueue::clearPlayQueue Apr 16 16:17:27 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:27 flynn volumio[961]: info: CoreStateMachine::pushEmptyState Apr 16 16:17:27 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:27 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:27 flynn sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:27 flynn sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:27 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:27 flynn python[963]: 2024-04-16 16:17:27,921 - engineio.client - INFO - Received packet MESSAGE data 2["pushState",{"status":"stop","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":0,"duration":136,"samplerate":"44.1 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"}] Apr 16 16:17:27 flynn python[963]: 2024-04-16 16:17:27,948 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:27 flynn python[963]: 2024-04-16 16:17:27,995 - 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"}}]}] Apr 16 16:17:28 flynn python[963]: 2024-04-16 16:17:28,016 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:28 flynn sudo[1756]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:28 flynn python[963]: 2024-04-16 16:17:28,035 - 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}] Apr 16 16:17:28 flynn python[963]: 2024-04-16 16:17:28,063 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Apr 16 16:17:28 flynn volumio[961]: info: sending command... Apr 16 16:17:28 flynn python[963]: 2024-04-16 16:17:28,073 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:28 flynn python[963]: 2024-04-16 16:17:28,110 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:28 flynn sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:28 flynn volumio[961]: info: Apr 16 16:17:28 flynn volumio[961]: ---------------------------- MPD announces state update: player Apr 16 16:17:28 flynn volumio[961]: info: parsing response... Apr 16 16:17:28 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:28 flynn sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:28 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:28 flynn volumio[961]: info: sending command... Apr 16 16:17:28 flynn volumio[961]: info: parsing response... Apr 16 16:17:28 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:28 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:28 flynn volumio[961]: info: sending command... Apr 16 16:17:28 flynn sudo[1762]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:28 flynn volumio[961]: info: parsing response... Apr 16 16:17:28 flynn volumio[961]: info: ControllerMpd::parseTrackInfo Apr 16 16:17:28 flynn volumio[961]: info: ControllerMpd::pushState Apr 16 16:17:28 flynn volumio[961]: info: CoreCommandRouter::servicePushState Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::syncState Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:28 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:28 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:28 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:28 flynn python[963]: 2024-04-16 16:17:28,521 - 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"}}]}] Apr 16 16:17:28 flynn python[963]: 2024-04-16 16:17:28,533 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:28 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:28 flynn volumio[961]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pippi Langstrumpf","artist":"Wizo","album":null,"uri":"INTERNAL/Wizo - Pippi Langstrumpf.mp3","trackType":"mp3"} Apr 16 16:17:28 flynn volumio[961]: info: CURRENT POSITION 1 Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::syncState stateService stop Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:28 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:28 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:28 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:28 flynn sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:28 flynn volumio[961]: info: No code Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:28 flynn sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:28 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:28 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:28 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:28 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:28 flynn sudo[1779]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:28 flynn sudo[1784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:28 flynn python[963]: 2024-04-16 16:17:28,974 - 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}] Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:28,991 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:29,026 - 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"}}]}] Apr 16 16:17:29 flynn sudo[1784]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:29,047 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:29,072 - 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}] Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:29,108 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:29,121 - 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"}}]}] Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:29,149 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:29,167 - 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}] Apr 16 16:17:29 flynn python[963]: 2024-04-16 16:17:29,178 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:29 flynn sudo[1784]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:29 flynn volumio[961]: info: ------------------------------ 952ms Apr 16 16:17:29 flynn sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:29 flynn sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:29 flynn sudo[1791]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:30 flynn python[963]: 2024-04-16 16:17:30,437 - engineio.client - INFO - Sending packet PING data None Apr 16 16:17:30 flynn python[963]: 2024-04-16 16:17:30,476 - engineio.client - INFO - Received packet PONG data None Apr 16 16:17:31 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:31 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,268 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."image" FROM "rfid_card" AS "t1" WHERE ("t1"."id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,283 - socketio.client - INFO - Emitting event "clearQueue" [/] Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,289 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Apr 16 16:17:31 flynn volumio[961]: info: CoreCommandRouter::volumioClearQueue Apr 16 16:17:31 flynn python[963]: ('!!!! CARD ====> ', {'uid': '04206492506480000000', 'uid_int': 36139094235160580L}, ) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,298 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"rfid": "{'uid': '04206492506480000000', 'uid_int': 36139094235160580L}"}, "type": "rfid"} Apr 16 16:17:31 flynn volumio[961]: info: CoreStateMachine::ClearQueue Apr 16 16:17:31 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:31 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:31 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:31 flynn volumio[961]: info: CorePlayQueue::clearPlayQueue Apr 16 16:17:31 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:31 flynn volumio[961]: info: CoreStateMachine::pushEmptyState Apr 16 16:17:31 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:31 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,356 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?)', [36139094235160580L]) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,409 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,466 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,511 - 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"}}]}] Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,540 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:31 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,551 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,606 - 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}] Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,637 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,631 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,648 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,676 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,721 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,743 - socketio.client - INFO - Emitting event "addToQueue" [/] Apr 16 16:17:31 flynn sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:31 flynn python[963]: 2024-04-16 16:17:31,749 - engineio.client - INFO - Sending packet MESSAGE data 2["addToQueue",[{"uri":"music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","service":"mpd"},{"uri":"music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3","service":"mpd"}]] Apr 16 16:17:31 flynn sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:31 flynn volumio[961]: info: CoreCommandRouter::volumioAddQueueItems Apr 16 16:17:31 flynn volumio[961]: info: CoreStateMachine::addQueueItems Apr 16 16:17:31 flynn volumio[961]: info: CorePlayQueue::addQueueItems Apr 16 16:17:31 flynn volumio[961]: info: Adding Item to queue: music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 Apr 16 16:17:31 flynn volumio[961]: info: Exploding uri music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 in service mpd Apr 16 16:17:31 flynn volumio[961]: info: Adding Item to queue: music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:31 flynn volumio[961]: info: Exploding uri music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3 in service mpd Apr 16 16:17:31 flynn sudo[1814]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:31 flynn volumio[961]: info: ALBUMART /albumart?cacheid=980&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FPippi%20Langstrumpf%20-%20Intro%20%5B1971%5D%20%5BlPOdvUUw6dc%5D.mp3&metadata=false Apr 16 16:17:31 flynn volumio[961]: info: URI /mnt/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 Apr 16 16:17:31 flynn volumio[961]: info: ALBUMART /albumart?cacheid=980&web=Wizo//extralarge&path=%2Fmnt%2FINTERNAL%2FWizo%20-%20Pippi%20Langstrumpf.mp3&metadata=false Apr 16 16:17:31 flynn volumio[961]: info: URI /mnt/INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:32 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:32 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,081 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[{"uri":"mnt/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","service":"mpd","name":"Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=980&web=//extralarge&path=%2FINTERNAL&metadata=false","duration":81,"trackType":"mp3"},{"uri":"mnt/INTERNAL/Wizo - Pippi Langstrumpf.mp3","service":"mpd","name":"Pippi Langstrumpf","artist":"Wizo","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=980&web=Wizo//extralarge&path=%2FINTERNAL&metadata=false","duration":136,"trackType":"mp3"}]] Apr 16 16:17:32 flynn volumio[961]: info: CoreStateMachine::updateTrackBlock Apr 16 16:17:32 flynn volumio[961]: info: CorePlayQueue::getTrackBlock Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,108 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,123 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Add to queue"}] Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,137 - socketio.client - INFO - Received event "pushToastMessage" [/] Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,764 - socketio.client - INFO - Emitting event "play" [/] Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,770 - engineio.client - INFO - Sending packet MESSAGE data 2["play"] Apr 16 16:17:32 flynn python[963]: (56, 1, 1, 1, 1, 0) Apr 16 16:17:32 flynn python[963]: !!!! NO CARD !!!! Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,776 - socketio.client - INFO - Emitting event "stop" [/] Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,782 - engineio.client - INFO - Sending packet MESSAGE data 2["stop"] Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,784 - socketio.client - INFO - Emitting event "clearQueue" [/] Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,790 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Apr 16 16:17:32 flynn volumio[961]: info: CoreCommandRouter::volumioPlay Apr 16 16:17:32 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:32 flynn volumio[961]: info: CoreStateMachine::play index undefined Apr 16 16:17:32 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:32 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:32 flynn volumio[961]: info: CoreStateMachine::startPlaybackTimer Apr 16 16:17:32 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:32 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,848 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."image" FROM "rfid_card" AS "t1" WHERE ("t1"."id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,862 - socketio.client - INFO - Emitting event "clearQueue" [/] Apr 16 16:17:32 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:32 flynn volumio[961]: info: ControllerMpd::clearAddPlayTracks INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:32 flynn volumio[961]: info: ControllerMpd::sendMpdCommand stop Apr 16 16:17:32 flynn volumio[961]: info: sending command... Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,867 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Apr 16 16:17:32 flynn python[963]: ('!!!! CARD ====> ', {'uid': '04206492506480000000', 'uid_int': 36139094235160580L}, ) Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,893 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Play","message":"Started playback of Pippi Langstrumpf"}] Apr 16 16:17:32 flynn python[963]: 2024-04-16 16:17:32,912 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"rfid": "{'uid': '04206492506480000000', 'uid_int': 36139094235160580L}"}, "type": "rfid"} Apr 16 16:17:32 flynn volumio[961]: info: CoreCommandRouter::volumioStop Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:33 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::volumioClearQueue Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::ClearQueue Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:33 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:33 flynn volumio[961]: info: CorePlayQueue::clearPlayQueue Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:32,947 - socketio.client - INFO - Received event "pushToastMessage" [/] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:32,987 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?)', [36139094235160580L]) Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,045 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:33 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::pushEmptyState Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,105 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,173 - 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"}}]}] Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,187 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,209 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::volumioClearQueue Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::ClearQueue Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:33 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:33 flynn volumio[961]: info: CorePlayQueue::clearPlayQueue Apr 16 16:17:33 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,277 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::pushEmptyState Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,336 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,353 - socketio.client - INFO - Emitting event "addToQueue" [/] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,363 - engineio.client - INFO - Sending packet MESSAGE data 2["addToQueue",[{"uri":"music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","service":"mpd"},{"uri":"music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3","service":"mpd"}]] Apr 16 16:17:33 flynn sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:33 flynn sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,522 - 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}] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,537 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,544 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,561 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,585 - 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"}}]}] Apr 16 16:17:33 flynn sudo[1832]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,604 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,643 - 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}] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,666 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,677 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:33 flynn python[963]: 2024-04-16 16:17:33,700 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:33 flynn sudo[1837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:33 flynn volumio[961]: info: parsing response... Apr 16 16:17:33 flynn volumio[961]: info: ControllerMpd::sendMpdCommand clear Apr 16 16:17:33 flynn volumio[961]: info: sending command... Apr 16 16:17:33 flynn sudo[1837]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:33 flynn volumio[961]: info: CoreCommandRouter::volumioAddQueueItems Apr 16 16:17:33 flynn volumio[961]: info: CoreStateMachine::addQueueItems Apr 16 16:17:33 flynn volumio[961]: info: CorePlayQueue::addQueueItems Apr 16 16:17:33 flynn volumio[961]: info: Adding Item to queue: music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 Apr 16 16:17:33 flynn volumio[961]: info: Exploding uri music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 in service mpd Apr 16 16:17:33 flynn volumio[961]: info: Adding Item to queue: music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:33 flynn volumio[961]: info: Exploding uri music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3 in service mpd Apr 16 16:17:33 flynn sudo[1837]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:33 flynn volumio[961]: info: Apr 16 16:17:33 flynn volumio[961]: ---------------------------- MPD announces system playlist update Apr 16 16:17:33 flynn volumio[961]: info: Ignoring MPD Status Update Apr 16 16:17:33 flynn volumio[961]: info: ALBUMART /albumart?cacheid=980&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FPippi%20Langstrumpf%20-%20Intro%20%5B1971%5D%20%5BlPOdvUUw6dc%5D.mp3&metadata=false Apr 16 16:17:33 flynn volumio[961]: info: URI /mnt/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 Apr 16 16:17:33 flynn volumio[961]: info: ALBUMART /albumart?cacheid=980&web=Wizo//extralarge&path=%2Fmnt%2FINTERNAL%2FWizo%20-%20Pippi%20Langstrumpf.mp3&metadata=false Apr 16 16:17:33 flynn volumio[961]: info: URI /mnt/INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:33 flynn volumio[961]: info: parsing response... Apr 16 16:17:34 flynn volumio[961]: info: ControllerMpd::sendMpdCommand add "INTERNAL/Wizo - Pippi Langstrumpf.mp3" Apr 16 16:17:34 flynn volumio[961]: info: sending command... Apr 16 16:17:34 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:34 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,060 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[{"uri":"mnt/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","service":"mpd","name":"Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=980&web=//extralarge&path=%2FINTERNAL&metadata=false","duration":81,"trackType":"mp3"},{"uri":"mnt/INTERNAL/Wizo - Pippi Langstrumpf.mp3","service":"mpd","name":"Pippi Langstrumpf","artist":"Wizo","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=980&web=Wizo//extralarge&path=%2FINTERNAL&metadata=false","duration":136,"trackType":"mp3"}]] Apr 16 16:17:34 flynn volumio[961]: info: CoreStateMachine::updateTrackBlock Apr 16 16:17:34 flynn volumio[961]: info: CorePlayQueue::getTrackBlock Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,092 - engineio.client - INFO - Received packet MESSAGE data 2["pushToastMessage",{"type":"success","title":"Add to queue"}] Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,081 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,118 - socketio.client - INFO - Received event "pushToastMessage" [/] Apr 16 16:17:34 flynn volumio[961]: info: Apr 16 16:17:34 flynn volumio[961]: info: Apr 16 16:17:34 flynn volumio[961]: ---------------------------- MPD announces system playlist update Apr 16 16:17:34 flynn volumio[961]: info: Ignoring MPD Status Update Apr 16 16:17:34 flynn volumio[961]: info: ------------------------------ 221ms Apr 16 16:17:34 flynn volumio[961]: info: parsing response... Apr 16 16:17:34 flynn volumio[961]: info: ControllerMpd::sendMpdCommand play Apr 16 16:17:34 flynn volumio[961]: info: sending command... Apr 16 16:17:34 flynn volumio[961]: info: ------------------------------ 91ms Apr 16 16:17:34 flynn volumio[961]: info: parsing response... Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,381 - socketio.client - INFO - Emitting event "play" [/] Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,386 - engineio.client - INFO - Sending packet MESSAGE data 2["play"] Apr 16 16:17:34 flynn volumio[961]: info: Apr 16 16:17:34 flynn volumio[961]: ---------------------------- MPD announces state update: player Apr 16 16:17:34 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:34 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:34 flynn volumio[961]: info: sending command... Apr 16 16:17:34 flynn python[963]: (56, 1, 1, 1, 1, 0) Apr 16 16:17:34 flynn python[963]: !!!! NO CARD !!!! Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,419 - socketio.client - INFO - Emitting event "stop" [/] Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,433 - engineio.client - INFO - Sending packet MESSAGE data 2["stop"] Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,435 - socketio.client - INFO - Emitting event "clearQueue" [/] Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,465 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Apr 16 16:17:34 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:34 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,546 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."image" FROM "rfid_card" AS "t1" WHERE ("t1"."id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:34 flynn volumio[961]: info: Apr 16 16:17:34 flynn volumio[961]: ---------------------------- MPD announces state update: player Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,579 - wsock - DEBUG - sending broadcast to all connected websocket clients : {"action": "event", "data": {"rfid": "{'uid': '04206492506480000000', 'uid_int': 36139094235160580L}"}, "type": "rfid"} Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,577 - socketio.client - INFO - Emitting event "clearQueue" [/] Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,606 - engineio.client - INFO - Sending packet MESSAGE data 2["clearQueue",""] Apr 16 16:17:34 flynn volumio[961]: info: ControllerMpd::getState Apr 16 16:17:34 flynn volumio[961]: info: ControllerMpd::sendMpdCommand status Apr 16 16:17:34 flynn volumio[961]: info: parsing response... Apr 16 16:17:34 flynn volumio[961]: info: sending command... Apr 16 16:17:34 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:34 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:34 flynn volumio[961]: info: sending command... Apr 16 16:17:34 flynn volumio[961]: info: CoreCommandRouter::volumioPlay Apr 16 16:17:34 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:34 flynn volumio[961]: info: CoreStateMachine::play index undefined Apr 16 16:17:34 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:34 flynn volumio[961]: info: CoreCommandRouter::volumioStop Apr 16 16:17:34 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:34 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:34 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:34 flynn volumio[961]: info: CoreCommandRouter::volumioClearQueue Apr 16 16:17:34 flynn python[963]: ('!!!! CARD ====> ', {'uid': '04206492506480000000', 'uid_int': 36139094235160580L}, ) Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,695 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?)', [36139094235160580L]) Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,777 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:34 flynn volumio[961]: info: CoreStateMachine::ClearQueue Apr 16 16:17:34 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:34 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:34 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:34 flynn volumio[961]: info: CorePlayQueue::clearPlayQueue Apr 16 16:17:34 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:34 flynn python[963]: 2024-04-16 16:17:34,872 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:34,960 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:35 flynn volumio[961]: info: CoreStateMachine::pushEmptyState Apr 16 16:17:35 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:35 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,053 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."name", "t1"."rfidcard_id" FROM "playlist" AS "t1" WHERE ("t1"."rfidcard_id" = ?) LIMIT ? OFFSET ?', [36139094235160580L, 1, 0]) Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,124 - peewee - DEBUG - ('SELECT "t1"."id", "t1"."uri", "t1"."service", "t1"."playlist_id" FROM "song" AS "t1" WHERE ("t1"."playlist_id" = ?)', ['0a433cd4668f4593abce1c02eedaebc7']) Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,149 - socketio.client - INFO - Emitting event "addToQueue" [/] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,158 - engineio.client - INFO - Sending packet MESSAGE data 2["addToQueue",[{"uri":"music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3","service":"mpd"},{"uri":"music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3","service":"mpd"}]] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,254 - 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"}}]}] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,267 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:35 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:35 flynn volumio[961]: info: CoreCommandRouter::volumioClearQueue Apr 16 16:17:35 flynn volumio[961]: info: CoreStateMachine::ClearQueue Apr 16 16:17:35 flynn volumio[961]: info: CoreStateMachine::stop Apr 16 16:17:35 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:35 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:35 flynn volumio[961]: info: CorePlayQueue::clearPlayQueue Apr 16 16:17:35 flynn volumio[961]: info: CorePlayQueue::saveQueue Apr 16 16:17:35 flynn volumio[961]: info: CoreStateMachine::pushEmptyState Apr 16 16:17:35 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:35 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:35 flynn sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:35 flynn sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:35 flynn sudo[1860]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:35 flynn volumio[961]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:17:35 flynn volumio[961]: info: CorePlayQueue::getTrack 1 Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,791 - 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}] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,807 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,816 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,833 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,859 - 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"}}]}] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,893 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,910 - 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}] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,948 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,956 - engineio.client - INFO - Received packet MESSAGE data 2["pushQueue",[]] Apr 16 16:17:35 flynn python[963]: 2024-04-16 16:17:35,977 - socketio.client - INFO - Received event "pushQueue" [/] Apr 16 16:17:36 flynn sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:36 flynn volumio[961]: info: parsing response... Apr 16 16:17:36 flynn volumio[961]: info: parsing response... Apr 16 16:17:36 flynn volumio[961]: info: ControllerMpd::parseState Apr 16 16:17:36 flynn volumio[961]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:17:36 flynn volumio[961]: info: ControllerMpd::parseTrackInfo Apr 16 16:17:36 flynn volumio[961]: info: sending command... Apr 16 16:17:36 flynn volumio[961]: info: ControllerMpd::pushState Apr 16 16:17:36 flynn sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:36 flynn volumio[961]: info: CoreCommandRouter::servicePushState Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::syncState Apr 16 16:17:36 flynn volumio[961]: info: CorePlayQueue::getTrack 0 Apr 16 16:17:36 flynn volumio[961]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pippi Langstrumpf","artist":"Wizo","album":null,"uri":"INTERNAL/Wizo - Pippi Langstrumpf.mp3","trackType":"mp3"} Apr 16 16:17:36 flynn volumio[961]: info: CURRENT POSITION 0 Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::syncState stateService play Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::pushState Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::getState Apr 16 16:17:36 flynn volumio[961]: info: CorePlayQueue::getTrack 0 Apr 16 16:17:36 flynn sudo[1869]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:36 flynn volumio[961]: info: CoreCommandRouter::volumioPushState Apr 16 16:17:36 flynn volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,179 - socketio.client - INFO - Emitting event "play" [/] Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,182 - engineio.client - INFO - Sending packet MESSAGE data 2["play"] Apr 16 16:17:36 flynn python[963]: (56, 1, 0, 1, 1, 1) Apr 16 16:17:36 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,193 - lctl - DEBUG - BT 2 Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,209 - socketio.client - INFO - Emitting event "prev" [/] Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,228 - engineio.client - INFO - Sending packet MESSAGE data 2["prev"] Apr 16 16:17:36 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:36 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,334 - 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"}}]}] Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,354 - socketio.client - INFO - Received event "pushMultiRoomDevices" [/] Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,383 - 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}] Apr 16 16:17:36 flynn python[963]: 2024-04-16 16:17:36,398 - socketio.client - INFO - Received event "pushState" [/] Apr 16 16:17:36 flynn volumio[961]: info: ------------------------------ 2024ms Apr 16 16:17:36 flynn volumio[961]: info: CoreCommandRouter::volumioAddQueueItems Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::addQueueItems Apr 16 16:17:36 flynn volumio[961]: info: CorePlayQueue::addQueueItems Apr 16 16:17:36 flynn volumio[961]: info: Adding Item to queue: music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 Apr 16 16:17:36 flynn volumio[961]: info: Exploding uri music-library/INTERNAL/Pippi Langstrumpf - Intro [1971] [lPOdvUUw6dc].mp3 in service mpd Apr 16 16:17:36 flynn volumio[961]: info: Adding Item to queue: music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3 Apr 16 16:17:36 flynn volumio[961]: info: Exploding uri music-library/INTERNAL/Wizo - Pippi Langstrumpf.mp3 in service mpd Apr 16 16:17:36 flynn volumio[961]: info: CoreCommandRouter::volumioPlay Apr 16 16:17:36 flynn volumio[961]: UNSET VOLATILE Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::play index undefined Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:17:36 flynn volumio[961]: info: CoreCommandRouter::volumioPrevious Apr 16 16:17:36 flynn sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:17:36 flynn volumio[961]: info: CoreStateMachine::previous Apr 16 16:17:36 flynn volumio[961]: info: CorePlayQueue::getTrack 0 Apr 16 16:17:36 flynn volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 16:17:36 flynn volumio[961]: TypeError: Cannot read property 'service' of undefined Apr 16 16:17:36 flynn volumio[961]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1447:105) Apr 16 16:17:36 flynn volumio[961]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:98:27) Apr 16 16:17:36 flynn volumio[961]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:244:31) Apr 16 16:17:36 flynn volumio[961]: at emitNone (events.js:106:13) Apr 16 16:17:36 flynn volumio[961]: at Socket.emit (events.js:208:7) Apr 16 16:17:36 flynn volumio[961]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 16 16:17:36 flynn volumio[961]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 16 16:17:36 flynn volumio[961]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 16 16:17:36 flynn sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:36 flynn volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 16:17:36 flynn sudo[1884]: pam_unix(sudo:session): session closed for user root Apr 16 16:17:37 flynn python[963]: (56, 1, 0, 1, 1, 1) Apr 16 16:17:37 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:37 flynn python[963]: 2024-04-16 16:17:37,178 - lctl - DEBUG - BT 2 Apr 16 16:17:37 flynn python[963]: 2024-04-16 16:17:37,190 - socketio.client - INFO - Emitting event "prev" [/] Apr 16 16:17:37 flynn python[963]: 2024-04-16 16:17:37,192 - engineio.client - INFO - Sending packet MESSAGE data 2["prev"] Apr 16 16:17:38 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:38 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:38 flynn python[963]: (56, 1, 0, 1, 1, 1) Apr 16 16:17:38 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:38 flynn python[963]: 2024-04-16 16:17:38,514 - lctl - DEBUG - BT 2 Apr 16 16:17:38 flynn python[963]: 2024-04-16 16:17:38,522 - socketio.client - INFO - Emitting event "prev" [/] Apr 16 16:17:38 flynn python[963]: 2024-04-16 16:17:38,524 - engineio.client - INFO - Sending packet MESSAGE data 2["prev"] Apr 16 16:17:39 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:39 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:39 flynn python[963]: (56, 1, 0, 1, 1, 1) Apr 16 16:17:39 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:39 flynn python[963]: 2024-04-16 16:17:39,273 - lctl - DEBUG - BT 2 Apr 16 16:17:39 flynn python[963]: 2024-04-16 16:17:39,280 - socketio.client - INFO - Emitting event "prev" [/] Apr 16 16:17:39 flynn python[963]: 2024-04-16 16:17:39,283 - engineio.client - INFO - Sending packet MESSAGE data 2["prev"] Apr 16 16:17:40 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:40 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:40 flynn python[963]: (56, 1, 0, 1, 1, 1) Apr 16 16:17:40 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:40 flynn python[963]: 2024-04-16 16:17:40,158 - lctl - DEBUG - BT 2 Apr 16 16:17:40 flynn python[963]: 2024-04-16 16:17:40,163 - socketio.client - INFO - Emitting event "prev" [/] Apr 16 16:17:40 flynn python[963]: 2024-04-16 16:17:40,175 - engineio.client - INFO - Sending packet MESSAGE data 2["prev"] Apr 16 16:17:40 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:40 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:41 flynn python[963]: (56, 1, 1, 0, 1, 1) Apr 16 16:17:41 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:41 flynn python[963]: 2024-04-16 16:17:40,992 - lctl - DEBUG - BT 3 Apr 16 16:17:41 flynn sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-16 16:16 Apr 16 16:17:41 flynn sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:17:41 flynn python[963]: (56, 1, 1, 1, 1, 1) Apr 16 16:17:41 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:41 flynn python[963]: (56, 1, 1, 0, 1, 1) Apr 16 16:17:41 flynn python[963]: (7, ' --> ', '04206492506480000000', ' --> ', (36139094235160580L,)) Apr 16 16:17:41 flynn python[963]: 2024-04-16 16:17:41,773 - lctl - DEBUG - BT 3 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"