volumio@volumiosala:/$ sudo journalctl -f -o cat
info: AFTER: SPOP HAS 0 PROMISE IN STACK
volumio : TTY=pts/0 ; PWD=/run/systemd/journal ; USER=root ; COMMAND=/bin/journalctl -f -o cat -u volspotconnect2.service
pam_unix(sudo:session): session opened for user root by volumio(uid=0)
2020-11-12 18:58:44 [SPTF] 18:58:44.797 I [file_streamer_simple.cpp:769] Request for file e183f683f81bc59fac501fa82608312491aba270 complete (code: 0)
2020-11-12 18:58:57 [SPTF] 18:58:57.398 I [file_streamer_simple.cpp:769] Request for file e183f683f81bc59fac501fa82608312491aba270 complete (code: 0)
pam_unix(sudo:session): session closed for user root
2020-11-12 18:59:09 [SPTF] 18:59:09.997 I [file_streamer_simple.cpp:769] Request for file e183f683f81bc59fac501fa82608312491aba270 complete (code: 0)
2020-11-12 18:59:22 [SPTF] 18:59:22.201 I [file_streamer_simple.cpp:769] Request for file e183f683f81bc59fac501fa82608312491aba270 complete (code: 0)
volumio : TTY=pts/0 ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -f -o cat
pam_unix(sudo:session): session opened for user root by volumio(uid=0)
2020-11-12 18:59:34 [SPTF] 18:59:34.597 I [file_streamer_simple.cpp:769] Request for file e183f683f81bc59fac501fa82608312491aba270 complete (code: 0)
2020-11-12 18:59:46 [SPTF] 18:59:46.772 I [file_streamer_simple.cpp:769] Request for file e183f683f81bc59fac501fa82608312491aba270 complete (code: 0)
2020-11-12 18:59:58 [SPTF] 18:59:58.809 I [file_streamer_simple.cpp:769] Request for file e183f683f81bc59fac501fa82608312491aba270 complete (code: 0)
info: CoreCommandRouter::volumioSeek
info: CoreStateMachine::seek
info: CorePlayQueue::getTrack 2
info: TRACKBLOCK {"uri":"spotify:track:5ZRnBEsfv1l5dnnzANNowF","service":"spop","name":"Metropolis - Live In England 1981","artist":"Motörhead","album":"No Sleep 'Til Hammersmith (Live) [Live; Deluxe Edition]","type":"song","duration":211,"tracknumber":3,"albumart":"https://i.scdn.co/image/ab67616d0000b27311608760260da490db21375b","samplerate":"320Kbps","bitdepth":"16 bit","trackType":"spotify"}
info: CoreStateMachine::startPlaybackTimer
info: CorePlayQueue::getTrack 2
info: [1605207598912] ControllerSpop::seek to 192000
info: [1605207598913] ControllerSpop::sendSpopCommand
info: ADDING DEFER FOR COMMAND seek 192000
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 2
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info:
[1605207598928] ---------------------------- Spop announces state update
info: [1605207598929] ControllerSpop::parseState
info: [1605207598929] ControllerSpop::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 2
verbose: STATE SERVICE {"status":"play","position":0,"seek":192000,"duration":211,"samplerate":"320Kbps","bitdepth":null,"channels":null,"artist":"Motörhead","title":"Metropolis - Live In England 1981","album":"No Sleep 'Til Hammersmith (Live) [Live; Deluxe Edition]"}
verbose: CURRENT POSITION 2
info: CoreStateMachine::syncState stateService play
info: CoreStateMachine::syncState currentStatus play
info: Received an update from plugin. extracting info from payload
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 2
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 2
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: [1605207598948] ------------------------------ 20ms
info: FIRST BRANCH
info: BEFORE: SPOP HAS 1 PROMISE IN STACK
info: AFTER: SPOP HAS 0 PROMISE IN STACK
2020-11-12 18:59:59 [SPTF] 18:59:59.434 I [file_streamer_simple.cpp:769] Request for file e183f683f81bc59fac501fa82608312491aba270 complete (code: 0)
info: CorePlayQueue::getTrack 2
info: CorePlayQueue::getTrack 3
info: Prefetching next song
2020-11-12 19:00:18 [SPTF] 19:00:18.255 I [social_presence.cpp:135] Presence: A track was played
info:
[1605207618255] ---------------------------- Spop announces state update
info: [1605207618256] ControllerSpop::parseState
info: [1605207618256] ControllerSpop::pushState
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 2
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CorePlayQueue::getTrack 2
verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"320Kbps","bitdepth":null,"channels":null}
verbose: CURRENT POSITION 2
info: CoreStateMachine::syncState stateService stop
info: CoreStateMachine::syncState currentStatus play
info: CoreStateMachine::play index undefined
info: CoreStateMachine::setConsumeUpdateService undefined
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 3
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CorePlayQueue::getTrack 3
info: CoreStateMachine::startPlaybackTimer
info: CorePlayQueue::getTrack 3
info: [1605207618265] ControllerSpop::clearAddPlayTrack
info: {"uri":"spotify:track:3YUQ2RcUv3XkECsjICj0UM","service":"spop","name":"The Hammer - Live In England 1981","artist":"Motörhead","album":"No Sleep 'Til Hammersmith (Live) [Live; Deluxe Edition]","type":"song","duration":185,"tracknumber":4,"albumart":"https://i.scdn.co/image/ab67616d0000b27311608760260da490db21375b","samplerate":"320Kbps","bitdepth":"16 bit","trackType":"spotify"}
info: [1605207618265] ControllerSpop::sendSpopCommand
info: ADDING DEFER FOR COMMAND uplay
info: [1605207618266] ------------------------------ 11ms
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 3
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info:
[1605207618369] ---------------------------- Spop announces state update
info: [1605207618370] ControllerSpop::parseState
info: [1605207618370] ControllerSpop::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 3
verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":"320Kbps","bitdepth":null,"channels":null,"artist":"Motörhead","title":"The Hammer - Live In England 1981","album":"No Sleep 'Til Hammersmith (Live) [Live; Deluxe Edition]"}
verbose: CURRENT POSITION 3
info: CoreStateMachine::syncState stateService play
info: CoreStateMachine::syncState currentStatus stop
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 3
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: [1605207618375] ------------------------------ 6ms
info: FIRST BRANCH
info: BEFORE: SPOP HAS 1 PROMISE IN STACK
info: AFTER: SPOP HAS 0 PROMISE IN STACK
2020-11-12 19:00:18 [SPTF] 19:00:18.823 I [file_streamer_simple.cpp:769] Request for file 77d11ee5e14d21f5bc9bf63f9d08f2e0f7509cc9 complete (code: 0)
2020-11-12 19:00:19 [SPTF] 19:00:19.020 I [file_streamer_simple.cpp:769] Request for file 77d11ee5e14d21f5bc9bf63f9d08f2e0f7509cc9 complete (code: 0)
info: CoreCommandRouter::volumioPause
info: CoreStateMachine::pause
info: CoreStateMachine::stPlaybackTimer
info: CoreStateMachine::servicePause
info: CorePlayQueue::getTrack 3
info: CoreCommandRouter::servicePause
info: [1605207624748] ControllerSpop::pause
info: [1605207624748] ControllerSpop::sendSpopCommand
info: ADDING DEFER FOR COMMAND toggle
info:
[1605207625175] ---------------------------- Spop announces state update
info: [1605207625176] ControllerSpop::parseState
info: [1605207625176] ControllerSpop::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 3
verbose: STATE SERVICE {"status":"pause","position":0,"seek":6733,"duration":185,"samplerate":"320Kbps","bitdepth":null,"channels":null,"artist":"Motörhead","title":"The Hammer - Live In England 1981","album":"No Sleep 'Til Hammersmith (Live) [Live; Deluxe Edition]"}
verbose: CURRENT POSITION 3
info: CoreStateMachine::syncState stateService pause
info: CoreStateMachine::syncState currentStatus pause
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 3
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreStateMachine::stPlaybackTimer
info: [1605207625181] ------------------------------ 6ms
info: FIRST BRANCH
info: BEFORE: SPOP HAS 1 PROMISE IN STACK
info: AFTER: SPOP HAS 0 PROMISE IN STACK
2020-11-12 19:00:48 [SPTF] 19:00:48.349 I [offline-mgr:2056] 0 files unlocked. 0 images unlocked
The transition is at the end.