Randomly playing end of previously played track
-
@mipper
What settings do you have for the fading options in Settings / Backend? -
-
@mipper
Maybe it's happening only when switching between MP3 and Ogg Vorbis songs, I'll try to reproduce it. -
I could not reproduce this.
Maye this is the same bug as https://github.com/strawberrymusicplayer/strawberry/issues/1535? -
@jonas I don't think it's the same as the linked bug. This issue occurs some time after the song ended. It also repeats over time; the same song ending can be played multiple times with a fair amount of time passing between them, and indeed other songs chipping in in between.
It looks like the idea of swapping between mp3/ogg may have been a red herring. I've had an example this today which involved only mp3 files.
Oct 7 09:29:10 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:10.723 DEBUG GstEngine:583 Stream from URL "file:///srv/Music/T/Temple Of The Dog/1991 - Temple Of The Dog/09 - Four Walled World.mp3" about to end in 12 seconds. Fuge: 1100 + Gap: 12000 Oct 7 09:29:10 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:10.740 DEBUG GstEngine:1128 Got stream info for "file:///srv/Music/L/lostprophets/2012%20-%20Weapons/10%20-%20Can't%20Get%20Enough.mp3:" "MPEG" Oct 7 09:29:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:18.252 DEBUG GstEnginePipeline:1387 Stream from URL "file:///srv/Music/T/Temple%20Of%20The%20Dog/1991%20-%20Temple%20Of%20The%20Dog/09%20-%20Four%20Walled%20World.mp3" about to finish. Oct 7 09:29:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:18.252 DEBUG GstEnginePipeline:2143 Setting next URL to "file:///srv/Music/L/lostprophets/2012%20-%20Weapons/10%20-%20Can't%20Get%20Enough.mp3" Oct 7 09:29:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:18.274 INFO GstEnginePipeline:1565 Ignoring error 4536 12 "Your GStreamer installation is missing a plug-in." "../gst/playback/gsturidecodebin.c(1027): no_more_pads_full (): /GstPlayBin:pipeline-2-pipeline/GstURIDecodeBin:uridecodebin11:\nno suitable plugins found:\n../gst/playback/gstdecodebin2.c(4701): gst_decode_bin_expose (): /GstPlayBin:pipeline-2-pipeline/GstURIDecodeBin:uridecodebin11/GstDecodeBin:decodebin13:\nno suitable plugins found:\nMissing decoder: MPEG-1 Layer 3 (MP3) (audio/mpeg, mpegversion=(int)1, mpegaudioversion=(int)1, layer=(int)3, rate=(int)44100, channels=(int)2, parsed=(boolean)true)\n" when loading next track Oct 7 09:29:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:18.359 INFO GstEnginePipeline:1565 Ignoring error 4536 12 "Your GStreamer installation is missing a plug-in." "../gst/playback/gsturidecodebin.c(1027): no_more_pads_full (): /GstPlayBin:pipeline-2-pipeline/GstURIDecodeBin:uridecodebin11:\nno suitable plugins found:\n../gst/playback/gstdecodebin2.c(4701): gst_decode_bin_expose (): /GstPlayBin:pipeline-2-pipeline/GstURIDecodeBin:uridecodebin11/GstDecodeBin:decodebin13:\nno suitable plugins found:\nMissing decoder: MPEG-1 Layer 3 (MP3) (audio/mpeg, mpegversion=(int)1, mpegaudioversion=(int)1, layer=(int)3, rate=(int)44100, channels=(int)2, parsed=(boolean)true)\n" when loading next track Oct 7 09:29:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:18.359 INFO GstEnginePipeline:1565 Ignoring error 4535 1 "Internal data stream error." "../libs/gst/base/gstbaseparse.c(3681): gst_base_parse_loop (): /GstPlayBin:pipeline-2-pipeline/GstURIDecodeBin:uridecodebin11/GstDecodeBin:decodebin13/GstMpegAudioParse:mpegaudioparse167:\nstreaming stopped, reason not-linked (-1)" when loading next track Oct 7 09:29:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:18.360 INFO GstEnginePipeline:1565 Ignoring error 4535 1 "Internal data stream error." "../libs/gst/base/gstbaseparse.c(3681): gst_base_parse_loop (): /GstPlayBin:pipeline-2-pipeline/GstURIDecodeBin:uridecodebin11/GstDecodeBin:decodebin13/GstMpegAudioParse:mpegaudioparse167:\nstreaming stopped, reason not-linked (-1)" when loading next track Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.527 DEBUG GstEnginePipeline:181 Pipeline 2 deleted Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.527 DEBUG MainWindow:1467 Song changed to "lostprophets" "Weapons" "Can't Get Enough" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.537 INFO MoodbarLoader:175 Creating moodbar data for "/srv/Music/L/lostprophets/2012 - Weapons/10 - Can't Get Enough.mp3" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.537 DEBUG HtmlLyricsProvider:75 "songlyrics.com" Sending request for QUrl("https://www.songlyrics.com/lostprophets/can-t-get-enough-lyrics/") Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.539 DEBUG Player:836 Playing song "Can't Get Enough" QUrl("file:///srv/Music/L/lostprophets/2012 - Weapons/10 - Can't Get Enough.mp3") position 0 Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.539 DEBUG GstEnginePipeline:639 "pulsesink" has volume, enabling volume synchronization. Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.539 DEBUG GstEnginePipeline:955 Connecting volume notify on 0x5e2eaafec7b0 Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.539 DEBUG GstEnginePipeline:810 Setting buffer duration: 4000000000 low watermark: 0.33 high watermark: 0.99 Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.539 DEBUG GstEnginePipeline:1831 Setting pipeline 3 state to "Paused" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.539 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Null" to "Ready" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.545 DEBUG GstEnginePipeline:1852 Pipeline 3 state successfully set to "Paused" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.558 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Null" to "Ready" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.567 DEBUG GstEnginePipeline:1767 Buffering started Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.577 DEBUG CollectionModel:547 Container key for 465 "Temple Of The Dog - Four Walled World" is uchanged, only updating song metadata. Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.578 DEBUG GstEngine:1128 Got stream info for "file:///srv/Music/L/lostprophets/2012%20-%20Weapons/10%20-%20Can't%20Get%20Enough.mp3:" "MPEG" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.598 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Ready" to "Paused" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.598 DEBUG GstEnginePipeline:1689 Pipeline is active Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.598 DEBUG CollectionModel:633 Song metadata for 465 "Temple Of The Dog - Four Walled World" changed Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.602 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Ready" to "Paused" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.605 DEBUG GstEnginePipeline:1778 Buffering finished Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.605 DEBUG GstEnginePipeline:1831 Setting pipeline 3 state to "Playing" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.605 DEBUG GstEnginePipeline:980 3 event latency Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.605 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 09:29:22 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:22.606 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 09:29:23 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:23.065 INFO MoodbarLoader:188 Moodbar data generated successfully for "/srv/Music/L/lostprophets/2012 - Weapons/10 - Can't Get Enough.mp3" Oct 7 09:29:23 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:23.423 DEBUG PlaylistBackend:339 Saving playlist 1 Oct 7 09:29:23 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:23.522 DEBUG HtmlLyricsProvider:119 "songlyrics.com" Got lyrics for "lostprophets" "Weapons" "Can't Get Enough" Oct 7 09:29:23 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:23.522 DEBUG LyricsFetcherSearch:136 Using lyrics from "songlyrics.com" for "lostprophets" "Can't Get Enough" with score -0.5 Oct 7 09:29:43 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:43.864 DEBUG GstEnginePipeline:1831 Setting pipeline 3 state to "Paused" Oct 7 09:29:43 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:43.865 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Playing" to "Paused" Oct 7 09:29:43 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:43.870 DEBUG GstEnginePipeline:1852 Pipeline 3 state successfully set to "Paused" Oct 7 09:29:43 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:29:43.872 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Playing" to "Paused" Oct 7 09:57:57 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:57:57.293 DEBUG GstEnginePipeline:1831 Setting pipeline 3 state to "Playing" Oct 7 09:57:57 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:57:57.294 DEBUG GstEnginePipeline:980 3 event latency Oct 7 09:57:57 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:57:57.295 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 09:57:57 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:57:57.298 DEBUG GstEnginePipeline:1852 Pipeline 3 state successfully set to "Playing" Oct 7 09:57:57 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 09:57:57.300 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 10:02:25 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 10:02:25.223 DEBUG GstEngine:583 Stream from URL "file:///srv/Music/L/lostprophets/2012 - Weapons/10 - Can't Get Enough.mp3" about to end in 12 seconds. Fuge: 1100 + Gap: 120
But I have a new theory. I believe the example above occurred when I restarted playback this morning. Temple of the Dog was playing as I put my machine to sleep on Friday night with playback paused. This morning when I restarted we saw the issue. My theory now is the exception we're seeing is somehow connected to periods where the player is unpaused after being paused for some kind of extended period.
-
I'm almost sure being on pause for an extended period is causing this. I have just come back from lunch and restarted playback. Boom! Got the missing plugin error and it's left the file that was playing and the next file open. I paused the track at 12:14:13 and restarted at 12:51:11 so around 36 minutes between those two events.
Now, if I remember correctly, when I restarted playback I also reset the song back to the begining. In fact, looking at the logs, I may have paused the track again shortly after resetting it. Can't be sure but maybe you can tell from the logs:
Oct 7 12:14:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:14:13.465 DEBUG GstEnginePipeline:1852 Pipeline 3 state successfully set to "Paused" Oct 7 12:14:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:14:13.467 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Playing" to "Paused" Oct 7 12:51:11 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:11.587 DEBUG GstEnginePipeline:1831 Setting pipeline 3 state to "Playing" Oct 7 12:51:11 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:11.589 DEBUG GstEnginePipeline:980 3 event latency Oct 7 12:51:11 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:11.589 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 12:51:11 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:11.594 DEBUG GstEnginePipeline:1852 Pipeline 3 state successfully set to "Playing" Oct 7 12:51:11 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:11.595 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.980 DEBUG MainWindow:1467 Song changed to "Trust" "Trust IV" "Par Compromission" Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.981 INFO MoodbarLoader:139 Loading cached moodbar data for "/srv/Music/T/Trust/1983 - Trust IV/01 - Par Compromission.mp3" Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.981 DEBUG Player:836 Playing song "Par Compromission" QUrl("file:///srv/Music/T/Trust/1983 - Trust IV/01 - Par Compromission.mp3") position 0 Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.990 DEBUG GstEnginePipeline:639 "pulsesink" has volume, enabling volume synchronization. Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.990 DEBUG GstEnginePipeline:955 Connecting volume notify on 0x5e2eaba9ff30 Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.991 DEBUG GstEnginePipeline:810 Setting buffer duration: 4000000000 low watermark: 0.33 high watermark: 0.99 Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.992 DEBUG GstEnginePipeline:388 Finishing pipeline 3 Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.992 DEBUG GstEnginePipeline:1831 Setting pipeline 3 state to "Null" Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.993 DEBUG GstEnginePipeline:1831 Setting pipeline 4 state to "Paused" Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.994 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Null" to "Ready" Oct 7 12:51:13 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:13.997 DEBUG GstEnginePipeline:1852 Pipeline 4 state successfully set to "Paused" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.013 DEBUG GstEnginePipeline:1852 Pipeline 3 state successfully set to "Null" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.013 DEBUG GstEngine:961 Pipeline 3 finished Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.014 DEBUG GstEnginePipeline:181 Pipeline 3 deleted Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.014 DEBUG GstEngine:986 1 pipelines are active Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.021 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Null" to "Ready" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.025 DEBUG GstEnginePipeline:1767 Buffering started Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.072 DEBUG GstEngine:1128 Got stream info for "file:///srv/Music/T/Trust/1983%20-%20Trust%20IV/01%20-%20Par%20Compromission.mp3:" "MPEG" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.073 DEBUG CollectionModel:547 Container key for 601 "Trust - Par Compromission" is uchanged, only updating song metadata. Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.076 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Ready" to "Paused" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.076 DEBUG GstEnginePipeline:1689 Pipeline is active Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.080 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Ready" to "Paused" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.082 DEBUG GstEnginePipeline:1778 Buffering finished Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.082 DEBUG GstEnginePipeline:1831 Setting pipeline 4 state to "Playing" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.082 DEBUG GstEnginePipeline:980 4 event latency Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.082 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.082 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.094 DEBUG CollectionModel:633 Song metadata for 601 "Trust - Par Compromission" changed Oct 7 12:51:14 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:51:14.923 DEBUG PlaylistBackend:339 Saving playlist 1 Oct 7 12:52:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:52:30.650 DEBUG GstEnginePipeline:1831 Setting pipeline 4 state to "Paused" Oct 7 12:52:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:52:30.651 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Playing" to "Paused" Oct 7 12:52:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:52:30.653 DEBUG GstEnginePipeline:1852 Pipeline 4 state successfully set to "Paused" Oct 7 12:52:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 12:52:30.653 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Playing" to "Paused" Oct 7 13:09:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:30.467 DEBUG GstEnginePipeline:1831 Setting pipeline 4 state to "Playing" Oct 7 13:09:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:30.478 DEBUG GstEnginePipeline:980 4 event latency Oct 7 13:09:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:30.478 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 13:09:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:30.478 DEBUG GstEnginePipeline:1852 Pipeline 4 state successfully set to "Playing" Oct 7 13:09:30 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:30.478 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 13:09:54 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:54.172 DEBUG GstEnginePipeline:1831 Setting pipeline 4 state to "Paused" Oct 7 13:09:54 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:54.173 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Playing" to "Paused" Oct 7 13:09:54 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:54.178 DEBUG GstEnginePipeline:1852 Pipeline 4 state successfully set to "Paused" Oct 7 13:09:54 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:09:54.181 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Playing" to "Paused" Oct 7 13:10:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:10:18.808 DEBUG GstEnginePipeline:1831 Setting pipeline 4 state to "Playing" Oct 7 13:10:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:10:18.809 DEBUG GstEnginePipeline:980 4 event latency Oct 7 13:10:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:10:18.809 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 13:10:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:10:18.813 DEBUG GstEnginePipeline:1852 Pipeline 4 state successfully set to "Playing" Oct 7 13:10:18 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:10:18.816 DEBUG GstEnginePipeline:1686 Pipeline state changed from "Paused" to "Playing" Oct 7 13:12:44 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:12:44.723 DEBUG GstEngine:583 Stream from URL "file:///srv/Music/T/Trust/1983 - Trust IV/01 - Par Compromission.mp3" about to end in 12 seconds. Fuge: 1100 + Gap: 12000 Oct 7 13:12:44 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:12:44.747 DEBUG GstEngine:1128 Got stream info for "file:///srv/Music/M/Metallica/2023%20-%2072%20Seasons/11%20-%20Room%20of%20Mirrors.ogg:" "Ogg Vorbis" Oct 7 13:12:51 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:12:51.680 DEBUG GstEnginePipeline:1387 Stream from URL "file:///srv/Music/T/Trust/1983%20-%20Trust%20IV/01%20-%20Par%20Compromission.mp3" about to finish. Oct 7 13:12:51 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:12:51.680 DEBUG GstEnginePipeline:2143 Setting next URL to "file:///srv/Music/M/Metallica/2023%20-%2072%20Seasons/11%20-%20Room%20of%20Mirrors.ogg" Oct 7 13:12:51 sydney org.strawberrymusicplayer.strawberry.desktop[670555]: 13:12:51.754 INFO GstEnginePipeline:1565 Ignoring error 4536 12 "Your GStreamer installation is missing a plug-in." "../gst/playback/gsturidecodebin.c(1027): no_more_pads_full (): /GstPlayBin:pipeline-4-pipeline/GstURIDecodeBin:uridecodebin56:\nno suitable plugins found:\n../gst/playback/gstdecodebin2.c(4701): gst_decode_bin_expose (): /GstPlayBin:pipeline-4-pipeline/GstURIDecodeBin:uridecodebin56/GstDecodeBin:decodebin58:\nno suitable plugins found:\nMissing decoder: Vorbis (audio/x-vorbis, rate=(int)44100, channels=(int)2, streamheader=(buffer)< 01766f72626973000000000244ac00000000000000e8030000000000b801, 03766f7262697334000000586970682e4f7267206c6962566f72626973204920323032303037303420285265647563
I'm trying to reproduce the issue by pausing a track for 30 minutes. Will let you know if I succeed.
-
Well I'm struggling to get a repeatable process, I can confirm it continues to happen but I haven't identified the cause. The only common thing is the
no suitable plugin
error. Something about how that gets handled is leaving the two files open. I'll keep trying to reproduce it. -
I've set up pipewire and set output to PulseAudio, tested playing for many hours a playlist on repeat containing MP3 and MP4 files, I still can't reproduce this, I've checked that no more than 1 file is open at a time.
If cross fading is disabled, it's hard to understand why several files are kept open at the same time.
-
Do you have any of the features replay gain, ebur128 or bs2b enabled in the backend settings?
-
@jonas Apologies. I didn't get any notification that you'd asked other questions so didn't see this until now. Looking at your conversation in #1582 it sounds promising that you may have tracked it down with brilliant help from jirislaby (Kudos, jirislaby. This has been driving me nuts! )
If I see any further issues after upgrading to a release with the fix in I'll let you know.
Many thanks to you both.
-
The issue with Strawberry leaving open pipelines which leads to open files should be fixed in version 1.2.1. However the new version won't work on Ubuntu 22.04 as Strawberry now requires a newer Qt version than is available on that Ubuntu version, consider upgrading to Ubuntu 24.04 (Noble).
The "Your GStreamer installation is missing a plug-in" error could be a GStreamer bug, maybe a newer GStreamer version solves that.