Mike, I don't have my /var/snd on a different server.
I am starting to suspect my soundcard is not ready. it usually happens after a short jingle. (2sec with no fade on segue out) I have disable 'main log output 2' (set to none) same for the soundpanel. Hoping this will help Regards Peter Here is a snippet of the log file : Nov 15 10:55:30 trudofm1 caed: Play - Card: 0 Stream: 2 Handle: 162 Length: 39140 Speed: 100000 Pitch: 0 Nov 15 10:55:30 trudofm1 caed: LoadPlayback Card: 0 Stream: 3 Name: /var/snd/010354_001.wav Handle: 163 Nov 15 10:55:30 trudofm1 caed: PlaybackPosition - Card: 0 Stream: 3 Pos: 84 Handle: 163 Nov 15 10:55:30 trudofm1 caed: Play - 1 Nov 15 10:55:30 trudofm1 caed: Play - Card: 0 Stream: 3 Handle: 163 Length: 223284 Speed: 100000 Pitch: 0 Nov 15 10:55:32 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/010873_001.wav) openWave() failed to open file Nov 15 10:55:32 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:32 trudofm1 rdairplay: finished event: Line: 446 Cart: 10873 Cut: 1 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:32 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/011919_001.wav) openWave() failed to open file Nov 15 10:55:32 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:32 trudofm1 rdairplay: rlm_facebook: sending pad update: "Now+playing+%22%22+by+%22%22" Nov 15 10:55:32 trudofm1 rdairplay: rlm_icecast2: sending pad update: "%20-%20" Nov 15 10:55:32 trudofm1 rdairplay: finished event: Line: 447 Cart: 11919 Cut: 1 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:32 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/015981_001.wav) openWave() failed to open file Nov 15 10:55:32 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:32 trudofm1 rdairplay: rlm_facebook: sending pad update: "Now+playing+%22%22+by+%22%22" Nov 15 10:55:32 trudofm1 rdairplay: rlm_icecast2: sending pad update: "%20-%20" Nov 15 10:55:32 trudofm1 rdairplay: finished event: Line: 448 Cart: 15981 Cut: 1 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:33 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/060036_020.wav) openWave() failed to open file Nov 15 10:55:33 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:33 trudofm1 rdairplay: rlm_facebook: sending pad update: "Now+playing+%22%22+by+%22%22" Nov 15 10:55:33 trudofm1 rdairplay: rlm_icecast2: sending pad update: "%20-%20" Nov 15 10:55:33 trudofm1 rdairplay: finished event: Line: 449 Cart: 60036 Cut: 20 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:33 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/060003_001.wav) openWave() failed to open file Nov 15 10:55:33 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:33 trudofm1 rdairplay: finished event: Line: 450 Cart: 60003 Cut: 1 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:33 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/000005_012.wav) openWave() failed to open file Nov 15 10:55:33 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:33 trudofm1 rdairplay: finished event: Line: 452 Cart: 5 Cut: 12 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:33 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/000003_022.wav) openWave() failed to open file Nov 15 10:55:33 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:33 trudofm1 rdairplay: finished event: Line: 453 Cart: 3 Cut: 22 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:33 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/000001_001.wav) openWave() failed to open file Nov 15 10:55:33 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:33 trudofm1 rdairplay: finished event: Line: 454 Cart: 1 Cut: 1 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:33 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/060003_001.wav) openWave() failed to open file Nov 15 10:55:33 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:33 trudofm1 rdairplay: finished event: Line: 455 Cart: 60003 Cut: 1 Card: 0 Stream: -1 Port: 0 Nov 15 10:55:33 trudofm1 caed: Error: hpiLoadPlayback(/var/snd/060001_003.wav) openWave() failed to open file Nov 15 10:55:33 trudofm1 caed: unable to allocate stream for card 0 Nov 15 10:55:33 trudofm1 rdairplay: finished event: Line: 456 Cart: 60001 Cut: 3 Card: 0 Stream: -1 Port: 0 Op do 15 nov. 2018 om 01:32 schreef Mike Carroll <druidl...@gmail.com>: > > We had that happen once when our /var/snd mount point went inaccessible. If > you have /var/snd on a different server, check that machine's logs. > > On Wed, Nov 14, 2018 at 3:13 PM Peter Claes <pcl...@cls-bvba.com> wrote: >> >> Hello, >> >> I have been seeing RdAirplay skipping through a whole bunch of carts. >> I Have noticed this fault for a few days. >> Even skipping full hours of carts. >> As you can see here : https://youtu.be/Dte8qZkZuVc >> >> I checked my messages (see below), and it seems to be a HPI problem at >> first look. >> >> Anyone any ideas ? >> >> If I reload the log, i can not reproduce the error. >> >> Might this be a Audioscience driver problem ? >> >> Regards >> Peter >> >> >> Messages : >> >> Nov 14 15:58:22 trudofm1 caed: Error: >> hpiLoadPlayback(/var/snd/060001_004.wav) openWave() failed to open >> file >> Nov 14 15:58:22 trudofm1 caed: unable to allocate stream for card 0 >> Nov 14 15:58:22 trudofm1 rdairplay: rlm_facebook: sending pad update: >> "Now+playing+%22%22+by+%22%22" >> Nov 14 15:58:22 trudofm1 rdairplay: rlm_icecast2: sending pad update: >> "%20-%20" >> Nov 14 15:58:22 trudofm1 caed: UnloadPlayback - Card: 0 Stream: 1 Handle: >> 142 >> Nov 14 15:58:22 trudofm1 rdairplay: finished event: Line: 842 Cart: >> 60001 Cut: 4 Card: 0 Stream: -1 Port: 0 >> Nov 14 15:58:22 trudofm1 caed: LoadPlayback Card: 0 Stream: 1 Name: >> /var/snd/013232_001.wav Handle: 145 >> Nov 14 15:58:22 trudofm1 rdairplay: started audio cart: Line: 843 >> Cart: 13232 Cut: 1 Pos: 0 Card: 0 Stream: 1 Port: 0 >> Nov 14 15:58:22 trudofm1 caed: PlaybackPosition - Card: 0 Stream: 1 >> Pos: 96 Handle: 145 >> Nov 14 15:58:22 trudofm1 caed: Play - 1 >> Nov 14 15:58:22 trudofm1 caed: Play - Card: 0 Stream: 1 Handle: 145 >> Length: 178034 Speed: 100000 Pitch: 0 >> Nov 14 15:58:22 trudofm1 rdairplay: rlm_facebook: sending pad update: >> "Now+playing+%22The+Ocean%22+by+%22Mike+Perry+ft+shy+martin%22" >> Nov 14 15:58:22 trudofm1 rdairplay: rlm_icecast2: sending pad update: >> "Mike%20Perry%20ft%20shy%20martin%20-%20The%20Ocean" >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_004 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=011982_001 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=010385_001 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_003 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060003_001 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=000001_001 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=000003_022 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=000005_012 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=000006_008 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060003_001 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060036_016 >> Nov 14 15:58:22 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=012370_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=011066_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=010018_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060002_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=020227_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=015762_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=016704_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_002 >> Nov 14 15:58:23 trudofm1 caed: StopPlayback - Card: 0 Stream: 0 Handle: 143 >> Nov 14 15:58:23 trudofm1 caed: UnloadPlayback - Card: 0 Stream: 0 Handle: >> 143 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=014686_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=017170_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060002_004 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=014779_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=010760_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=016231_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=012784_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=013521_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_004 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=016187_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=013895_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060002_003 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=016192_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=010771_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=020239_001 >> Nov 14 15:58:23 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_003 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=012148_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=010228_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_002 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060003_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=000001_022 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=000003_022 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=000005_012 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=000006_008 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060003_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060036_017 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=014821_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=014748_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=010166_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060002_002 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=017570_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=012099_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=012777_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=013073_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=012702_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060002_009 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=013170_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=015350_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=060001_004 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=017810_001 >> Nov 14 15:58:24 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=014439_001 >> Nov 14 15:58:25 trudofm1 rdairplay: LogPlay::StartEvent(): no >> audio,CUT=010714_001 >> Nov 14 15:58:25 trudofm1 rdairplay: finished event: Line: 778 Cart: >> 12195 Cut: 1 Card: 0 Stream: 3 Port: 0 >> Nov 14 15:58:25 trudofm1 caed: UnloadPlayback - Card: 0 Stream: 3 Handle: >> 124 >> Nov 14 15:58:25 trudofm1 rdairplay: finished event: Line: 779 Cart: >> 60001 Cut: 3 Card: 0 Stream: 2 Port: 0 >> Nov 14 15:58:25 trudofm1 caed: HPI Error: #104 - OBJ_NOT_OPEN, >> rdhpiplaystream.cpp line 822 >> Nov 14 15:58:25 trudofm1 caed: UnloadPlayback - Card: 0 Stream: 2 Handle: >> 144 >> Nov 14 15:58:25 trudofm1 kernel: hpimsgx.c:581 ffffa0098b6ed300 trying >> to close 0 outstream 2 owned by (null) >> _______________________________________________ >> Rivendell-dev mailing list >> Rivendell-dev@lists.rivendellaudio.org >> http://caspian.paravelsystems.com/mailman/listinfo/rivendell-dev > > > > -- > druidlabs.wordpress.com > flickr.com/photos/druidlabs/sets > _______________________________________________ > Rivendell-dev mailing list > Rivendell-dev@lists.rivendellaudio.org > http://caspian.paravelsystems.com/mailman/listinfo/rivendell-dev _______________________________________________ Rivendell-dev mailing list Rivendell-dev@lists.rivendellaudio.org http://caspian.paravelsystems.com/mailman/listinfo/rivendell-dev
