search

strawberrymusicplayer / strawberry

:strawberry: Strawberry Music Player
https://www.strawberrymusicplayer.org/
GNU General Public License v3.0
2.74k stars 191 forks source link

Resource not found when output is JACK via pipewire #882

Closed wildwestrom closed 2 years ago

wildwestrom commented 2 years ago

Describe the bug I'm using pipewire as a JACK server. When the output is set to a JACK server, sometimes the track doesn't play, with a pop-up error saying Resource not found.

To Reproduce Go to: Settings -> Backend -> Audio Output -> Output Set: Output audio to a JACK server

Play a song. Click Next Track,Previous Track or Play. It happens more often when clicking the next/back buttons quickly.

Expected behavior All songs in the a playlist should be found with no problem.

Screenshots: When I click a song and it fails.

$ strawberry
04:49:47.891 INFO  main:171                         Strawberry 1.0.1
04:49:47.891 INFO  main:172                         "Arch Linux unknown - (linux 5.16.2-arch1-1) [x86_64]"
04:49:47.917 DEBUG main:213                         Style: "fusion"
04:49:47.923 DEBUG Application:259                  Database(0x562ac73c2630) moved to thread QThread(0x562ac7400840)
04:49:47.923 DEBUG SCollection:69                   CollectionBackend(0x562ac73f9490) moved to thread QThread(0x562ac7400840)
04:49:47.927 DEBUG Application:259                  AlbumCoverLoader(0x562ac7437180) moved to thread QThread(0x562ac7476330)
04:49:47.928 DEBUG SCollection:101                  CollectionWatcher(0x562ac7481d80) moved to thread Thread(0x562ac748e2c0)
04:49:47.928 DEBUG Application:259                  TagReaderClient(0x562ac7393f30) moved to thread QThread(0x562ac7494a60)
04:49:47.928 DEBUG NetworkProxyFactory:54           Detected system proxy URLs: QList("", "", "", "")
04:49:47.928 DEBUG WorkerPool<HandlerType>:254      Using worker "strawberry-tagreader" from "/usr/bin"
04:49:47.928 DEBUG WorkerPool<HandlerType>:305      Starting worker 0x7fac93ffdf10 "/usr/bin/strawberry-tagreader" "/tmp/strawberry_690035073"
04:49:47.929 DEBUG WorkerPool<HandlerType>:305      Starting worker 0x7fac93ffdf10 "/usr/bin/strawberry-tagreader" "/tmp/strawberry_734271923"
04:49:47.930 DEBUG WorkerPool<HandlerType>:305      Starting worker 0x7fac93ffdf10 "/usr/bin/strawberry-tagreader" "/tmp/strawberry_3233209856"
04:49:47.931 DEBUG WorkerPool<HandlerType>:305      Starting worker 0x7fac93ffdf10 "/usr/bin/strawberry-tagreader" "/tmp/strawberry_1108159120"
04:49:47.937 INFO  main:57                          TagReader worker connecting to "/tmp/strawberry_734271923"
04:49:47.937 DEBUG WorkerPool<HandlerType>:328      Worker 0x7fac8800aaf0 connected to "/tmp/strawberry_734271923"
04:49:47.937 INFO  main:57                          TagReader worker connecting to "/tmp/strawberry_690035073"
04:49:47.937 DEBUG WorkerPool<HandlerType>:328      Worker 0x7fac8800aad0 connected to "/tmp/strawberry_690035073"
04:49:47.938 INFO  main:57                          TagReader worker connecting to "/tmp/strawberry_3233209856"
04:49:47.938 DEBUG WorkerPool<HandlerType>:328      Worker 0x7fac8800ab10 connected to "/tmp/strawberry_3233209856"
04:49:47.940 INFO  main:57                          TagReader worker connecting to "/tmp/strawberry_1108159120"
04:49:47.940 DEBUG WorkerPool<HandlerType>:328      Worker 0x7fac8800ab30 connected to "/tmp/strawberry_1108159120"
04:49:47.946 WARN  GlobalShortcutsManager:208       No global shortcuts enabled.
04:49:48.045 INFO  Player:900                       Registered URL handler for "subsonic"
04:49:48.047 DEBUG InternetServices:49              Added internet service "Subsonic"
04:49:48.047 INFO  Player:900                       Registered URL handler for "tidal"
04:49:48.052 DEBUG InternetServices:49              Added internet service "Tidal"
04:49:48.052 INFO  Player:900                       Registered URL handler for "qobuz"
04:49:48.057 DEBUG InternetServices:49              Added internet service "Qobuz"
04:49:48.157 DEBUG MainWindow:329                   Starting
04:49:48.176 DEBUG CoverProviders:109               Registered cover provider "Last.fm"
04:49:48.176 DEBUG CoverProviders:109               Registered cover provider "MusicBrainz"
04:49:48.176 DEBUG CoverProviders:109               Registered cover provider "Discogs"
04:49:48.176 DEBUG CoverProviders:109               Registered cover provider "Deezer"
04:49:48.176 DEBUG CoverProviders:109               Registered cover provider "Musixmatch"
04:49:48.176 DEBUG CoverProviders:109               Registered cover provider "Spotify"
04:49:48.176 DEBUG CoverProviders:109               Registered cover provider "Tidal"
04:49:48.176 DEBUG CoverProviders:109               Registered cover provider "Qobuz"
04:49:48.181 DEBUG LyricsProviders:108              Registered lyrics provider "AudD"
04:49:48.181 DEBUG LyricsProviders:108              Registered lyrics provider "Genius"
04:49:48.181 DEBUG LyricsProviders:108              Registered lyrics provider "Lyrics.ovh"
04:49:48.181 DEBUG LyricsProviders:108              Registered lyrics provider "LoloLyrics"
04:49:48.181 DEBUG LyricsProviders:108              Registered lyrics provider "Musixmatch"
04:49:48.181 DEBUG LyricsProviders:108              Registered lyrics provider "ChartLyrics"
04:49:48.193 DEBUG MainWindow:378                   Initializing player
04:49:48.198 DEBUG MainWindow:388                   Creating models
04:49:48.198 DEBUG MainWindow:395                   Creating models finished
04:49:48.199 DEBUG DeviceLister:67                  CddaLister(0x562ac838e000) moved to thread QThread(0x562ac838e050)
04:49:48.199 DEBUG DeviceLister:67                  Udisks2Lister(0x562ac8025f80) moved to thread QThread(0x562ac8381f00)
04:49:48.199 DEBUG DeviceLister:67                  GioLister(0x562ac8021290) moved to thread QThread(0x562ac803bf70)
04:49:48.199 DEBUG CddaLister:116                   No CD devices found
04:49:48.203 DEBUG Application:259                  PlaylistBackend(0x562ac8403350) moved to thread QThread(0x562ac7400840)
04:49:48.207 DEBUG Application:259                  RadioBackend(0x562ac843cc10) moved to thread QThread(0x562ac7400840)
04:49:48.208 DEBUG RadioServices:67                 Adding radio service: "SomaFM"
04:49:48.209 DEBUG logging:85(GLib-GIO)             _g_io_module_get_default: Found default implementation gvfs (GDaemonVfs) for ‘gio-vfs’
04:49:48.209 DEBUG RadioServices:67                 Adding radio service: "Radio Paradise"
04:49:48.209 DEBUG MainWindow:415                   Creating UI
04:49:48.223 DEBUG ScrobblerServices:49             Registered scrobbler service "Last.fm"
04:49:48.223 DEBUG ScrobblerServices:49             Registered scrobbler service "Libre.fm"
04:49:48.223 DEBUG ScrobblerServices:49             Registered scrobbler service "ListenBrainz"
04:49:48.223 DEBUG ScrobblerServices:49             Registered scrobbler service "Subsonic"
04:49:48.236 DEBUG MainWindow:844                   Creating playing widget
04:49:48.281 DEBUG MainWindow:896                   Loading settings
04:49:48.406 DEBUG MainWindow:1031                  Started QThread(0x562ac735ad60)
04:50:17.751 DEBUG Database:514                     Starting database integrity check
04:50:17.770 DEBUG Database:583                     Starting database backup                                       
# After clicking play on a song
04:50:35.064 DEBUG _MessageReplyBase:31             Waiting on ID 0
04:50:35.065 DEBUG TagReaderTagLib:817              Loading art from "/home/main/audio/music-library/tricot/3/09 18,19.flac"
04:50:35.065 DEBUG MainWindow:1347                  Song changed to "tricot" "3" "18,19"
04:50:35.067 DEBUG MessageReply<MessageType>:90     Releasing ID 0 (finished)
04:50:35.068 DEBUG _MessageReplyBase:33             Acquired ID 0
04:50:35.078 DEBUG MusixmatchLyricsProvider:90      MusixmatchLyrics: Sending request for "tricot" "1819" QUrl("https://www.musixmatch.com/lyrics/tricot/1819")
04:50:35.078 DEBUG Player:686                       Playing song "18,19" QUrl("file:///home/main/audio/music-library/tricot/3/09 18,19.flac") position 0
04:50:35.098 DEBUG GstEnginePipeline:536            Setting buffer duration: 4000000000 low watermark: 0.33 high watermark: 0.99
04:50:35.119 ERROR GstEnginePipeline:1110           ErrorMessageReceived ID: 1 Domain: 3094 Code: 3 Error: "Resource not found."
04:50:35.119 ERROR GstEnginePipeline:1111           ErrorMessageReceived ID: 1 Domain: 3094 Code: 3 Debug: "../gst-plugins-good/ext/jack/gstjackaudiosink.c(308): jack_shutdown_cb (): /GstPlayBin:pipeline-1-pipeline/GstPlaySink:playsink/GstBin:abin/GstBin:audiobin/GstJackAudioSink:pipeline-1-jackaudiosink:\nJack server shutdown"
04:50:35.121 ERROR GstEngine:545                    GStreamer error: 3094 3 "Resource not found."
04:50:35.132 WARN  unknown                          Wayland does not support QWindow::requestActivate() 
04:50:35.274 DEBUG ChartLyricsProvider:137          ChartLyrics: No lyrics for "tricot" "18,19"
04:50:35.292 DEBUG LoloLyricsProvider:149           LoloLyrics: No lyrics for "tricot" "18,19" "Error transferring http://api.lololyrics.com/0.5/getLyric?artist=tricot&track=18203C19 - server replied: Not Found (203)"
04:50:35.816 DEBUG AuddLyricsProvider:139           AudDLyrics: No lyrics for "tricot" "18,19"
04:50:36.023 DEBUG PlaylistBackend:335              Saving playlist 24
04:50:36.133 ERROR OVHLyricsProvider:120            OVHLyrics: "Error transferring https://api.lyrics.ovh/v1/tricot/18203C19 - server replied: Not Found (203)"
04:50:36.133 ERROR OVHLyricsProvider:120            OVHLyrics: "No lyrics found"
04:50:36.133 DEBUG OVHLyricsProvider:92             OVHLyrics: No lyrics for "tricot" "18,19"

System Information:

jonaski commented 2 years ago

This looks like a configuration issue or bug with jack/pipewire, the debug says "Jack server shutdown". I have very little knowledge of both jack and pipewire so I think you are better off asking for help in a pipewire or jack mailingslist/forum. There isn't anyone else helping with issues here.