Posts made by astrovark

So far, not had this happen after installing .23 and the qt6 library it needed. It still autoloads a picture if it can, and closes part of the collection list if it finds one, but hasn't skipped back in the track.

I can now reliably cause a skip-back by playing a file from an album that doesn't currently have cover art showing in the album collection list. It appears to occur as the image is linked somehow into the file.

Capture of *:4 debug when this occurred is at https://file.io/Mv1UzlzulrE0

This is on .17, I'll try and see if I can do the same on .23 (latest Debian)

Not sure if relevant - I do have mine skipping back in songs again, what I've noticed is that it appears to be coincidental with updating cover art (it's doing that automatically, not because I'm triggering a lookup manually whilst playing), and also I see a lot of what looks like a hex dump rapidly written to the screen. Are there specific markers I could look for for song start/when I press "Stop" on a repeating song so that I can try and trap just that part?

As an example:

26:23:05.088510589 1490048 0x7f09e4001460 INFO               baseparse gstbaseparse.c:4898:gst_base_parse_set_upstream_tags:<mpegaudioparse346> upstream tags: taglist, album=(string)"Yesterday\ Once\ More", artist=(string)Carpenters, comment=(string)"Greatest\ Hits\ -\ 1969-1983\012YEAR:\ 1998\ \ ID3G:\ \ 17", album-disc-count=(uint)2, album-disc-number=(uint)2, genre=(string)Pop, replaygain-album-gain=(double)-2.1499999999999999, replaygain-album-peak=(double)1.057426, replaygain-track-gain=(double)-1.97, replaygain-track-peak=(double)0.82459300000000002, title=(string)"Because\ We\ Are\ in\ Love\ \(The\ Wedding\ Song\)", track-count=(uint)14, track-number=(uint)9, date=(date)1998-01-01, container-format=(string)"APE\ tag";
26:23:05.094524047 1490048 0x7f09e4001460 INFO               GST_EVENT gstevent.c:918:gst_event_new_caps: creating caps event audio/mpeg, mpegversion=(int)1, mpegaudioversion=(int)1, layer=(int)3, rate=(int)44100, channels=(int)2, parsed=(boolean)true
26:23:05.094547035 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstpad.c:2142:gst_pad_unlink: unlinking mpegaudioparse346:src(0x562fa1d640a0) and decodepad242:proxypad1648(0x562fa1bb71d0)
26:23:05.094557689 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstpad.c:2197:gst_pad_unlink: unlinked mpegaudioparse346:src and decodepad242:proxypad1648
26:23:05.094565626 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2387:gst_pad_link_prepare: trying to link mpegaudioparse346:src and decodepad242:proxypad1648
26:23:05.094571611 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2595:gst_pad_link_full: linked mpegaudioparse346:src and decodepad242:proxypad1648, successful
26:23:05.094575766 1490048 0x7f09e4001460 INFO               GST_EVENT gstevent.c:1687:gst_event_new_reconfigure: creating reconfigure event
26:23:05.094759770 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstpad.c:2142:gst_pad_unlink: unlinking mpegaudioparse346:src(0x562fa1d640a0) and decodepad242:proxypad1648(0x562fa1bb71d0)
26:23:05.094768512 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstpad.c:2197:gst_pad_unlink: unlinked mpegaudioparse346:src and decodepad242:proxypad1648
26:23:05.094774934 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2387:gst_pad_link_prepare: trying to link mpegaudioparse346:src and decodepad242:proxypad1648
26:23:05.094780071 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2595:gst_pad_link_full: linked mpegaudioparse346:src and decodepad242:proxypad1648, successful
26:23:05.094784029 1490048 0x7f09e4001460 INFO               GST_EVENT gstevent.c:1687:gst_event_new_reconfigure: creating reconfigure event
26:23:05.094795338 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstpad.c:2142:gst_pad_unlink: unlinking mpegaudioparse346:src(0x562fa1d640a0) and decodepad242:proxypad1648(0x562fa1bb71d0)
26:23:05.094801739 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstpad.c:2197:gst_pad_unlink: unlinked mpegaudioparse346:src and decodepad242:proxypad1648
26:23:05.094807814 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2387:gst_pad_link_prepare: trying to link mpegaudioparse346:src and decodepad242:proxypad1648
26:23:05.094812782 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2595:gst_pad_link_full: linked mpegaudioparse346:src and decodepad242:proxypad1648, successful
26:23:05.094816635 1490048 0x7f09e4001460 INFO               GST_EVENT gstevent.c:1687:gst_event_new_reconfigure: creating reconfigure event
26:23:05.094827819 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstpad.c:2142:gst_pad_unlink: unlinking mpegaudioparse346:src(0x562fa1d640a0) and decodepad242:proxypad1648(0x562fa1bb71d0)
26:23:05.094833711 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstpad.c:2197:gst_pad_unlink: unlinked mpegaudioparse346:src and decodepad242:proxypad1648
26:23:05.094840054 1490048 0x7f09e4001460 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "avdec_mp3"
26:23:05.094856260 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstelement.c:758:gst_element_add_pad:<GstAudioDecoder@0x7f09c8016230> adding pad 'sink'
26:23:05.094864552 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstelement.c:758:gst_element_add_pad:<GstAudioDecoder@0x7f09c8016230> adding pad 'src'
26:23:05.094892791 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2387:gst_pad_link_prepare: trying to link mpegaudioparse346:src and avdec_mp3-327:sink
26:23:05.094899221 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2595:gst_pad_link_full: linked mpegaudioparse346:src and avdec_mp3-327:sink, successful
26:23:05.094903181 1490048 0x7f09e4001460 INFO               GST_EVENT gstevent.c:1687:gst_event_new_reconfigure: creating reconfigure event
26:23:05.094913462 1490048 0x7f09e4001460 INFO              GST_STATES gstelement.c:2816:gst_element_continue_state:<avdec_mp3-327> completed state change to READY
26:23:05.094918882 1490048 0x7f09e4001460 INFO              GST_STATES gstelement.c:2716:_priv_gst_element_state_changed:<avdec_mp3-327> notifying about state-changed NULL to READY (VOID_PENDING pending)
26:23:05.094932785 1490048 0x7f09e4001460 INFO        GST_ELEMENT_PADS gstelement.c:1016:gst_element_get_static_pad: found pad avdec_mp3-327:src
26:23:05.094941939 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2387:gst_pad_link_prepare: trying to link avdec_mp3-327:src and decodepad242:proxypad1648
26:23:05.094947031 1490048 0x7f09e4001460 INFO                GST_PADS gstpad.c:2595:gst_pad_link_full: linked avdec_mp3-327:src and decodepad242:proxypad1648, successful
26:23:05.094950880 1490048 0x7f09e4001460 INFO               GST_EVENT gstevent.c:1687:gst_event_new_reconfigure: creating reconfigure event
26:23:05.094955315 1490048 0x7f09e4001460 INFO               GST_EVENT gstpad.c:5985:gst_pad_send_event_unchecked:<avdec_mp3-327:src> Received event on flushing pad. Discarding
26:23:05.094975336 1490048 0x7f09e4001460 INFO              GST_STATES gstelement.c:2816:gst_element_continue_state:<avdec_mp3-327> completed state change to PAUSED
26:23:05.094981017 1490048 0x7f09e4001460 INFO              GST_STATES gstelement.c:2716:_priv_gst_element_state_changed:<avdec_mp3-327> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
26:23:05.101140200 1490048 0x7f09e4001460 INFO            audiodecoder gstaudiodecoder.c:2525:gst_audio_decoder_sink_eventfunc:<avdec_mp3-327> upstream stream tags: taglist, title=(string)"Because\ We\ Are\ in\ Love\ \(The\ Wedding\ Song\)", artist=(string)Carpenters, album=(string)"Yesterday\ Once\ More", datetime=(datetime)1998, comment=(string)"Greatest\ Hits\ -\ 1969-1983\012YEAR:\ 1998\ \ ID3G:\ \ 17", track-number=(uint)9, genre=(string)Pop, container-format=(string)"ID3\ tag", track-count=(uint)14, album-disc-count=(uint)2, album-disc-number=(uint)2, artist-sortname=(string)Carpenters, musicbrainz-trackid=(string)e64ed6d5-4cca-4940-a2ca-efa814f81aab, private-id3v2-frame=(sample){ 545858580000001b000003414c42554d415254495354534f52540043617270656e74657273:None:c2VnbWVudCwgZmxhZ3M9KEdzdFNlZ21lbnRGbGFncylHU1RfU0VHTUVOVF9GTEFHX05PTkUsIHJhdGU9KGRvdWJsZSkxLCBhcHBsaWVkLXJhdGU9KGRvdWJsZSkxLCBmb3JtYXQ9KEdzdEZvcm1hdCl0aW1lLCBiYXNlPShndWludDY0KTAsIG9mZnNldD0oZ3VpbnQ2NCkwLCBzdGFydD0oZ3VpbnQ2NCkwLCBzdG9wPShndWlu (followed by many lines of hex)

This song skipped back whilst playing, but I've no idea what bit of log to trap.

Could I exclude lines that only state

GST_STATES gstbin.c:2069:gst_bin_get_state_func:<pipeline-86-pipeline> getting state

This seems to be the same looking issue I saw on Debian (and I'm currently on a fully patched Bookworm), which I still cannot provoke reliably yet had it occur today (I'm still running .17 because it appears to happen less often that in newer versions). A track that will play properly most of the time will suddenly skip back in the early parts of the track, and then end early because the time runs out (as per the time bar) before the song ends. Sometimes the following tracks play perfectly, other times following tracks have the same skip issue.

Restarting Strawberry fixes it. Never saw this in Clementine with the same file.

My original post was at https://forum.strawberrymusicplayer.org/topic/1138/playback-issue-restarting-after-10ish-seconds, but as I couldn't reliably provoke it I didn't go futher. I might try the debug command and see if I can provoke it again - the problem being I might have to run Strawberry for a long time before it occurs, and restarting for me always clears the issue. No single track is guaranteed to make it happen.

@jonas Thanks - trying it - typically after having it for a few weeks with debug on I can't provoke it, but it also looks like it got an update from Debian as well. I'll keep trying!

Weird issue that I've started seeing from Strawberry, both from latest and earlier (back to .7 from the current cycle at least), that is not replicated on Clementine or other players. OS is Debian Bullseye, patched to current. I don't recall seeing it originally with .7 but regressing back to it from current didn't fix the issue.

Adding a track to the playlist (drag/drop, or double click) or when it starts a new track in the playlist, it will start to play the track but after around 10 seconds (seen between 7 and 14 seconds so far) the track will restart from the beginning. It doesn't restart the timeline however, and if the track is permitted to go to the end it will cut from the end the number of seconds it played before the restart. If I move the timeline pointer back it will restart properly but still cut, but if I actually stop the track and play again it will generally play properly.

It's also not every track or every time for a given track. When I launch from console (so as to see the console output) there is no indication of an issue or error.

Any good way to troubleshoot this further?