I have not had to analyze logs since moving to RD 3.4.1 and I see
that the operations logs do not include all log changes, so this is a
tough one. Obviously, you have a valid log with content for the next
day, so no log or an empty log is not the problem. And since there is
no notice in the operations log of a second chain to another log, I
have to assume that is also not the problem--although it might be,
since if there eventually was no second log, the operations log may not
have recorded the attempt to load it.
In comparing with my logs, your operations log shows 2x "caed:
StopPlayback" events in a row for every cart. I have only ever had one
of those for each event. Not sure what that means, but it differs from
our system which is RD 3.4.1.
My suggestion would be to NOT make the last 2 events hard-timed, and
instead, Insert Meta > Marker right before them that is hard-timed
to 23:59:30 and set as a "make next"/segue. We name that "Cue to time
log chain". That will advance the log to that point, and since it is
impossible to execute the log chain instantly, with the delay
instituted by the ID, that will prevent the next day's log from
executing the same command right after the log chain.
Unfortunately, it is impossible to see what is going on without RD
logging all the executions that are actually happening. My
recollection was that RD 2.x versions did log those. "Race" conditions
can occur in Rivendell executions, which is why it is often necessary
to put wait pauses in RD macros to prevent a later execution from
beating one that should occur first. At this point, I can only surmise
that some kind of race situation is happening on some days but not
others.
Below is the last half-hour of our day in the music log, and as it
played out in the operations log. We use the marker cue to time the
end of the music log for the day, in addition to timing the log chain,
because we do not have the manpower to time out each hour. We also use
all 3 log machines, and the operations log reflects what is going on
with the other 2. All 3 roll over without a hitch near midnight and
have for years now.
Hope this cures your problem.
Rivendell Log
Listing
Generated: 09/22/2020 Log: Production-2020-09-
21 Description: Production log for 2020-09-
Service: Production AutoRefresh Enabled:
No [none] [none]
-Type-- -Time---- Trans -Cart- -Group---- -Length- -Title------------
--------------- -Artist----------------------- -Source----- Line
...
Audio 22:19:00 SEGUE 114005 MUSIC 2:49 I Wanna Love Him So
Bad The Jelly Beans Music 402
Audio 22:22:00 SEGUE 121484 MUSIC 2:27 Too Much of
Nothing Peter, Paul & Mary Music 403
...
Marker H23:30:00 SEGUE :00 Cue to time log
chain Manual 423
Audio 23:16:00 SEGUE 124772 MUSIC 3:10 Give Us Your
Blessings [Mono] The Shangri-Las Music 424
Audio 23:34:00 SEGUE 122999 MUSIC 1:40 I Hate
Music Replacements Music
425
Audio 23:38:00 SEGUE 111401 MUSIC 3:07 This Girl Is a Woman
Now Gary Puckett and the Union Gap Music 426
Audio 23:41:00 SEGUE 131603 R&B 2:15 Stone Fox
[Mono] The Pac-
Keys Music 427
Audio 23:44:00 SEGUE 125034 MUSIC 5:07 The
Boxer Simon &
Garfunkel Music 428
Audio 23:48:00 SEGUE 112493 MUSIC 2:36 The 59th Street
Bridge Song (Feel Harpers Bizarre Music 429
Audio 23:52:00 SEGUE 113240 MUSIC 3:50 Good
Times INXS and Jimmy
Barnes Music 430
Audio 23:55:00 SEGUE 124462 MUSIC 3:29 Take Your Mama
[Radio Edit] Scissor Sister Music 431
Audio 23:58:00 SEGUE 116320 MUSIC 2:13
Calcutta Lawrence Welk and His Orchestr
Music 432
Chain 00:00:00 SEGUE Production-2020-09-
22 RDLogManager 433
====
Sep 21 23:24:18 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/114005_001.wav Handle: 208
Sep 21 23:24:18 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 104 Handle: 208
Sep 21 23:24:18 rivendell rdairplay: log engine: started audio cart:
Line: 473 Cart: 114005 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:24:18 rivendell caed: Play - Card: 1 Stream: 1 Handle:
208 Length: 169143 Speed: 100000 Pitch: 0
Sep 21 23:24:19 rivendell rdairplay: log engine: finished event: Line:
472 Cart: 131628 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 21 23:24:19 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 207
Sep 21 23:24:19 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 207
Sep 21 23:27:06 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/121484_001.wav Handle: 209
Sep 21 23:27:06 rivendell rdairplay: log engine: started audio cart:
Line: 474 Cart: 121484 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:27:06 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 156 Handle: 209
Sep 21 23:27:06 rivendell caed: Play - Card: 1 Stream: 0 Handle:
209 Length: 147723 Speed: 100000 Pitch: 0
Sep 21 23:27:07 rivendell caed: StopPlayback - Card: 1 Stream:
1 Handle: 208
Sep 21 23:27:07 rivendell rdairplay: log engine: finished event: Line:
473 Cart: 114005 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 21 23:27:07 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 208
Sep 21 23:29:32 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/214587_001.wav Handle: 210
Sep 21 23:29:32 rivendell rdairplay: log engine: started audio cart:
Line: 475 Cart: 214587 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:29:32 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 444 Handle: 210
Sep 21 23:29:32 rivendell caed: Play - Card: 1 Stream: 1 Handle:
210 Length: 187820 Speed: 100000 Pitch: 0
Sep 21 23:29:34 rivendell rdairplay: log engine: finished event: Line:
474 Cart: 121484 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 21 23:29:34 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 209
Sep 21 23:29:34 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 209
Sep 21 23:32:39 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/124772_001.wav Handle: 211
Sep 21 23:32:39 rivendell rdairplay: log engine: started audio cart:
Line: 495 Cart: 124772 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:32:39 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 130 Handle: 211
Sep 21 23:32:39 rivendell caed: Play - Card: 1 Stream: 0 Handle:
211 Length: 190093 Speed: 100000 Pitch: 0
Sep 21 23:32:40 rivendell rdairplay: log engine: finished event: Line:
475 Cart: 214587 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 21 23:32:40 rivendell caed: StopPlayback - Card: 1 Stream:
1 Handle: 210
Sep 21 23:32:40 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 210
Sep 21 23:35:48 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/122999_001.wav Handle: 212
Sep 21 23:35:48 rivendell rdairplay: log engine: started audio cart:
Line: 496 Cart: 122999 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:35:48 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 470 Handle: 212
Sep 21 23:35:48 rivendell caed: Play - Card: 1 Stream: 1 Handle:
212 Length: 100336 Speed: 100000 Pitch: 0
Sep 21 23:35:49 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 211
Sep 21 23:35:49 rivendell rdairplay: log engine: finished event: Line:
495 Cart: 124772 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 21 23:35:49 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 211
Sep 21 23:37:00 rivendell rdairplay: log engine: started macro cart:
Line: 361 Cart: 50001
Sep 21 23:37:00 rivendell ripcd: received rml: "PX 1 50002!" from
127.0.0.1
Sep 21 23:37:00 rivendell ripcd: received rml: "PS 2!" from 127.0.0.1
Sep 21 23:37:29 rivendell rdairplay: log engine: started macro cart:
Line: 497 Cart: 50002
Sep 21 23:37:29 rivendell rdairplay: log engine: finished event: Line:
496 Cart: 122999 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 21 23:37:29 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 212
Sep 21 23:37:29 rivendell ripcd: received rml: "PN 2!" from 127.0.0.1
Sep 21 23:37:29 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/060220_001.wav Handle: 213
Sep 21 23:37:29 rivendell rdairplay: log engine: started audio cart:
Line: 362 Cart: 60220 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:37:29 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 26 Handle: 213
Sep 21 23:37:29 rivendell caed: Play - Card: 1 Stream: 0 Handle:
213 Length: 15608 Speed: 100000 Pitch: 0
Sep 21 23:37:32 rivendell ripcd: received rml: "PS 1!" from 127.0.0.1
Sep 21 23:37:44 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 213
Sep 21 23:37:44 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/060002_001.wav Handle: 214
Sep 21 23:37:44 rivendell rdairplay: log engine: started audio cart:
Line: 363 Cart: 60002 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:37:44 rivendell rdairplay: log engine: finished event: Line:
362 Cart: 60220 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 21 23:37:44 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 104 Handle: 214
Sep 21 23:37:44 rivendell caed: Play - Card: 1 Stream: 1 Handle:
214 Length: 6687 Speed: 100000 Pitch: 0
Sep 21 23:37:44 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 213
Sep 21 23:37:51 rivendell rdairplay: log engine: started macro cart:
Line: 364 Cart: 50004
Sep 21 23:37:51 rivendell ripcd: received rml: "PN 1!" from 127.0.0.1
Sep 21 23:37:51 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/111401_001.wav Handle: 215
Sep 21 23:37:51 rivendell rdairplay: log engine: started audio cart:
Line: 498 Cart: 111401 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:37:51 rivendell rdairplay: log engine: finished event: Line:
363 Cart: 60002 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 21 23:37:51 rivendell caed: StopPlayback - Card: 1 Stream:
1 Handle: 214
Sep 21 23:37:51 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 78 Handle: 215
Sep 21 23:37:51 rivendell caed: Play - Card: 1 Stream: 0 Handle:
215 Length: 187454 Speed: 100000 Pitch: 0
Sep 21 23:37:51 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 214
Sep 21 23:37:54 rivendell ripcd: received rml: "PS 2!" from 127.0.0.1
Sep 21 23:40:54 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/131603_001.wav Handle: 216
Sep 21 23:40:54 rivendell rdairplay: log engine: started audio cart:
Line: 499 Cart: 131603 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:40:54 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 208 Handle: 216
Sep 21 23:40:54 rivendell caed: Play - Card: 1 Stream: 1 Handle:
216 Length: 135419 Speed: 100000 Pitch: 0
Sep 21 23:40:58 rivendell rdairplay: log engine: finished event: Line:
498 Cart: 111401 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 21 23:40:58 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 215
Sep 21 23:40:58 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 215
Sep 21 23:43:07 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/125034_001.wav Handle: 217
Sep 21 23:43:07 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 156 Handle: 217
Sep 21 23:43:07 rivendell caed: Play - Card: 1 Stream: 0 Handle:
217 Length: 307305 Speed: 100000 Pitch: 0
Sep 21 23:43:07 rivendell rdairplay: log engine: started audio cart:
Line: 500 Cart: 125034 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:43:10 rivendell caed: StopPlayback - Card: 1 Stream:
1 Handle: 216
Sep 21 23:43:10 rivendell rdairplay: log engine: finished event: Line:
499 Cart: 131603 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 21 23:43:10 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 216
Sep 21 23:46:51 rivendell rdservice: ran local maintenance routines
Sep 21 23:46:51 rivendell rdservice: ran system-wide maintenance
routines
Sep 21 23:48:14 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/112493_001.wav Handle: 218
Sep 21 23:48:14 rivendell rdairplay: log engine: started audio cart:
Line: 501 Cart: 112493 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:48:14 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 130 Handle: 218
Sep 21 23:48:14 rivendell caed: Play - Card: 1 Stream: 1 Handle:
218 Length: 156056 Speed: 100000 Pitch: 0
Sep 21 23:48:14 rivendell rdairplay: log engine: finished event: Line:
500 Cart: 125034 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 21 23:48:14 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 217
Sep 21 23:48:14 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 217
Sep 21 23:50:47 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/113240_001.wav Handle: 219
Sep 21 23:50:47 rivendell rdairplay: log engine: started audio cart:
Line: 502 Cart: 113240 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:50:47 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 104 Handle: 219
Sep 21 23:50:47 rivendell caed: Play - Card: 1 Stream: 0 Handle:
219 Length: 230243 Speed: 100000 Pitch: 0
Sep 21 23:50:50 rivendell caed: StopPlayback - Card: 1 Stream:
1 Handle: 218
Sep 21 23:50:50 rivendell rdairplay: log engine: finished event: Line:
501 Cart: 112493 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 21 23:50:50 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 218
Sep 21 23:54:33 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/124462_001.wav Handle: 220
Sep 21 23:54:33 rivendell rdairplay: log engine: started audio cart:
Line: 503 Cart: 124462 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:54:33 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 130 Handle: 220
Sep 21 23:54:33 rivendell caed: Play - Card: 1 Stream: 1 Handle:
220 Length: 209293 Speed: 100000 Pitch: 0
Sep 21 23:54:37 rivendell rdairplay: log engine: finished event: Line:
502 Cart: 113240 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 21 23:54:37 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 219
Sep 21 23:54:37 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 219
Sep 21 23:57:00 rivendell rdairplay: log engine: started macro cart:
Line: 365 Cart: 50001
Sep 21 23:57:00 rivendell ripcd: received rml: "PX 1 50002!" from
127.0.0.1
Sep 21 23:57:00 rivendell ripcd: received rml: "PS 2!" from 127.0.0.1
Sep 21 23:58:01 rivendell rdairplay: log engine: started macro cart:
Line: 504 Cart: 50002
Sep 21 23:58:01 rivendell ripcd: received rml: "PN 2!" from 127.0.0.1
Sep 21 23:58:01 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/060209_002.wav Handle: 221
Sep 21 23:58:01 rivendell rdairplay: log engine: started audio cart:
Line: 366 Cart: 60209 Cut: 2 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:58:01 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 679 Handle: 221
Sep 21 23:58:01 rivendell caed: Play - Card: 1 Stream: 0 Handle:
221 Length: 15333 Speed: 100000 Pitch: 0
Sep 21 23:58:02 rivendell rdairplay: log engine: finished event: Line:
503 Cart: 124462 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 21 23:58:02 rivendell caed: StopPlayback - Card: 1 Stream:
1 Handle: 220
Sep 21 23:58:02 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 220
Sep 21 23:58:04 rivendell ripcd: received rml: "PS 1!" from 127.0.0.1
Sep 21 23:58:16 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/063002_001.wav Handle: 222
Sep 21 23:58:16 rivendell rdairplay: log engine: started audio cart:
Line: 367 Cart: 63002 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:58:16 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 182 Handle: 222
Sep 21 23:58:16 rivendell caed: Play - Card: 1 Stream: 1 Handle:
222 Length: 31426 Speed: 100000 Pitch: 0
Sep 21 23:58:16 rivendell rdairplay: log engine: finished event: Line:
366 Cart: 60209 Cut: 2 Card: 1 Stream: 0 Port: 0
Sep 21 23:58:16 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 221
Sep 21 23:58:16 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 221
Sep 21 23:58:47 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/090001_007.wav Handle: 223
Sep 21 23:58:47 rivendell rdairplay: log engine: started audio cart:
Line: 368 Cart: 90001 Cut: 7 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:58:47 rivendell rdairplay: log engine: finished event: Line:
367 Cart: 63002 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 21 23:58:47 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 1280 Handle: 223
Sep 21 23:58:47 rivendell caed: Play - Card: 1 Stream: 0 Handle:
223 Length: 32600 Speed: 100000 Pitch: 0
Sep 21 23:58:47 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 222
Sep 21 23:59:20 rivendell caed: StopPlayback - Card: 1 Stream:
0 Handle: 223
Sep 21 23:59:20 rivendell rdairplay: log engine: chained to log: Line:
369 Log: PgmB-2020-09-22
Sep 21 23:59:20 rivendell rdairplay: log engine: finished event: Line:
368 Cart: 90001 Cut: 7 Card: 1 Stream: 0 Port: 0
Sep 21 23:59:20 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 223
Sep 21 23:59:20 rivendell ripcd: received rml: "LL 2 PgmB-2020-09-22
-2!" from 127.0.0.1
Sep 21 23:59:20 rivendell rdairplay: loaded log 'PgmB-2020-09-22' in
Aux 1 Log
Sep 21 23:59:20 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/060009_001.wav Handle: 224
Sep 21 23:59:20 rivendell rdairplay: log engine: started audio cart:
Line: 0 Cart: 60009 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:59:20 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 104 Handle: 224
Sep 21 23:59:20 rivendell caed: Play - Card: 1 Stream: 0 Handle:
224 Length: 8986 Speed: 100000 Pitch: 0
Sep 21 23:59:29 rivendell rdairplay: log engine: started macro cart:
Line: 1 Cart: 50004
Sep 21 23:59:29 rivendell ripcd: received rml: "PN 1!" from 127.0.0.1
Sep 21 23:59:29 rivendell caed: LoadPlayback Card: 1 Stream: 1 Name:
/var/snd/116320_001.wav Handle: 225
Sep 21 23:59:29 rivendell caed: PlaybackPosition - Card: 1 Stream:
1 Pos: 130 Handle: 225
Sep 21 23:59:29 rivendell rdairplay: log engine: started audio cart:
Line: 505 Cart: 116320 Cut: 1 Pos: 0 Card: 1 Stream: 1 Port: 0
Sep 21 23:59:29 rivendell caed: Play - Card: 1 Stream: 1 Handle:
225 Length: 133016 Speed: 100000 Pitch: 0
Sep 21 23:59:29 rivendell rdairplay: log engine: finished event: Line:
0 Cart: 60009 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 21 23:59:29 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 224
Sep 21 23:59:32 rivendell ripcd: received rml: "PS 2!" from 127.0.0.1
Sep 21 23:59:54 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/999998_001.wav Handle: 226
Sep 21 23:59:54 rivendell rdairplay: log engine: started audio cart:
Line: 23 Cart: 999998 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 21 23:59:54 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 0 Handle: 226
Sep 21 23:59:54 rivendell caed: Play - Card: 1 Stream: 0 Handle:
226 Length: 8515 Speed: 100000 Pitch: 0
Sep 22 00:00:02 rivendell rdairplay: log engine: chained to log: Line:
24 Log: Time-Tones
Sep 22 00:00:02 rivendell rdairplay: log engine: finished event: Line:
23 Cart: 999998 Cut: 1 Card: 1 Stream: 0 Port: 0
Sep 22 00:00:02 rivendell caed: UnloadPlayback - Card: 1 Stream:
0 Handle: 226
Sep 22 00:00:02 rivendell ripcd: received rml: "LL 3 Time-Tones -2!"
from 127.0.0.1
Sep 22 00:00:02 rivendell rdairplay: loaded log 'Time-Tones' in Aux Log
2
Sep 22 00:01:41 rivendell rdairplay: log engine: chained to log: Line:
506 Log: Production-2020-09-22
Sep 22 00:01:41 rivendell ripcd: received rml: "LL 1 Production-2020-
09-22 -2!" from 127.0.0.1
Sep 22 00:01:42 rivendell rdairplay: loaded log 'Production-2020-09-22'
in Main Log
Sep 22 00:01:42 rivendell caed: LoadPlayback Card: 1 Stream: 0 Name:
/var/snd/114599_001.wav Handle: 227
Sep 22 00:01:42 rivendell rdairplay: log engine: started audio cart:
Line: 1 Cart: 114599 Cut: 1 Pos: 0 Card: 1 Stream: 0 Port: 0
Sep 22 00:01:42 rivendell caed: PlaybackPosition - Card: 1 Stream:
0 Pos: 104 Handle: 227
Sep 22 00:01:42 rivendell caed: Play - Card: 1 Stream: 0 Handle:
227 Length: 263131 Speed: 100000 Pitch: 0
Sep 22 00:01:42 rivendell rdairplay: log engine: finished event: Line:
0 Cart: 116320 Cut: 1 Card: 1 Stream: 1 Port: 0
Sep 22 00:01:42 rivendell caed: StopPlayback - Card: 1 Stream:
1 Handle: 225
Sep 22 00:01:42 rivendell caed: UnloadPlayback - Card: 1 Stream:
1 Handle: 225
--Chuck
On Tu, 22 Sep 2020 21:09:53 +0000
Mark Murdock <[email protected]> wrote:
> To: Chuck <[email protected]>,
> "[email protected]"
> <[email protected]>
> Subject: Re: [RDD] RDAirPlay Crash Revisited
> Message-ID:
>
> <dm6pr12mb386578f53a7a1cb33ea0f60cb3...@dm6pr12mb3865.namprd12.prod.outlook.com>
>
> Content-Type: text/plain; charset="utf-8"
>
> Below is the end of the 9/21 log and the beginning of the 9/22 log:
>
> Audio 23:41:27 SEGUE 060701 LINERS :12 Airshow Enhancer
> Airshow Enhancer Music 764
> Audio 23:41:39 SEGUE 010131 MUSIC 3:13 The River
> Jordan Feliz Music 765
> Audio 23:44:51 SEGUE 060918 JINGLES :13 Jingles Overnight (1)
> MM #3 tk 10 Music 766
> Audio 23:45:03 SEGUE 011145 MUSIC 2:50 Who Can
> Cochran & Company Music 767
> Audio 23:47:53 SEGUE 060925 JINGLES :05 Features Jingle (Outside
> MD) Music 768
> Audio 23:47:57 SEGUE 011109 MUSIC 3:43 Fires
> Jordan St. Cyr (seer) Music 769
> Audio 23:51:40 SEGUE 060701 LINERS :12 Airshow Enhancer
> Airshow Enhancer Music 770
> Audio 23:51:52 SEGUE 011063 MUSIC 3:31 Amadeo (Still My God)
> Ryan Stevenson Music 771
> Audio 23:55:26 SEGUE 060920 JINGLES :09 Jingles Overnight (3)
> full sing Music 772
> Audio 23:55:38 SEGUE 010268 MUSIC 4:04 Jesus I Believe
> Big Daddy Weave Music 773
> Audio 23:59:31 SEGUE 060701 LINERS :12 Airshow Enhancer
> Airshow Enhancer Music 774
> Audio 23:59:43 SEGUE 010464 MUSIC 4:22 God With Us
> Mercy Me Music 775
> Audio 23:01:03 SEGUE 010046 MUSIC 3:49 You Love Me Anyway
> Sidewalk Prophets Music 776
> Audio H23:59:30 SEGUE 060003 LEGAL :11 Legal ID
> McConnell RDLogManager 777
> Chain H00:00:00 SEGUE Production-0922
> RDLogManager 778
>
> -Type-- -Time---- Trans -Cart- -Group---- -Length-
> -Title--------------------------- -Artist----------------------- -Source-----
> Line
> Audio 00:00:00 SEGUE 010038 MUSIC 3:52 Come As You Are
> David Crowder Music 0
> Audio 00:03:48 SEGUE 060701 LINERS :12 Airshow Enhancer
> Airshow Enhancer Music 1
> Audio 00:04:00 SEGUE 011104 MUSIC 2:59 Revolutionary
> Josh Wilson Music 2
> Audio 00:06:59 SEGUE 060921 JINGLES :09 Jingles Overnight (4)
> full sing Music 3
> Audio 00:07:11 SEGUE 011050 MUSIC 3:58 Alive and Breathing
> Matt Maher Music 4
> Audio 00:00:00 SEGUE 060702 LINERS :09 Celebrating Liner
> McConnell Manual 5
> Audio 00:11:29 SEGUE 011107 MUSIC 4:25 The Blessing
> Kari Jobe w/Cody Carnes Music 6
> Audio 00:15:56 SEGUE 060918 JINGLES :13 Jingles Overnight (1)
> MM #3 tk 10 Music 7
> Audio 00:16:08 SEGUE 011147 MUSIC 3:18 Victory Song
> Cori & Kelly Music 8
>
> The last thing that played before the ID was the Big Daddy Weave song.
>
> Thanks,
> Mark
>
> -----Original Message-----
> From: Chuck <[email protected]>
> Sent: Tuesday, September 22, 2020 11:43 AM
> To: [email protected]; Mark Murdock
> <[email protected]>
> Subject: Re: [RDD] RDAirPlay Crash Revisited
>
> Can you please post the last 15 minutes of the actual 9/21 log and the
> first 15 minutes of the 9/22 log?
>
> My operations logs all run from approximately 03:00 to 03:00 daily, so I
> don't think that is unusual. It would be good to get to the bottom of your
> problem, because even if a log mistake is causing the problem, it should not
> cause a crash of AirPlay.
>
> --Chuck
>
> On Tu 22 Sep 2020 14:32:17 +0000
> Mark Murdock <[email protected]> wrote:
>
> Once again RDAirPlay crashed completely after loading the new log for
> today, and I had to connect and re-start RDAirPlay. Below is the end
> of the operations log for 20200921. Can anyone see anything that would
> cause this crash? It appears nothing played after the legal ID
> (60003), though it did chain to the 0922 log. It also seems odd to me
> that the log for 9/21 continues until 3 AM on 9/22. This seems to be
> true for all the operations logs. Can anyone suggest anything? Thanks!
>
> Sep 21 23:58:01 studioc caed: LoadPlayback Card: 0 Stream: 1
> Name: /var/snd/060920_002.wav Handle: 168 Sep 21 23:58:01 studioc
> rdairplay: log engine: started audio cart:
> Line: 772 Cart: 60920 Cut: 2 Pos: 0 Card: 0 Stream: 1 Port: 0 Sep
> 21 23:58:01 studioc caed: PlaybackPosition - Card: 0 Stream:
> 1 Pos: 417 Handle: 168
> Sep 21 23:58:01 studioc caed: Play - Card: 0 Stream: 1 Handle:
> 168 Length: 5956 Speed: 100000 Pitch: 0 Sep 21 23:58:01 studioc
> rdairplay: log engine: finished event: Line:
> 771 Cart: 11063 Cut: 1 Card: 0 Stream: 0 Port: 1 Sep 21 23:58:01
> studioc caed: StopPlayback - Card: 0 Stream:
> 0 Handle: 167
> Sep 21 23:58:01 studioc caed: StopPlayback - Card: 0 Stream:
> 0 Handle: 167
> Sep 21 23:58:01 studioc caed: UnloadPlayback - Card: 0 Stream:
> 0 Handle: 167
> Sep 21 23:58:06 studioc caed: LoadPlayback Card: 0 Stream:
> 0 Name: /var/snd/010268_001.wav Handle: 169 Sep 21 23:58:06 studioc
> rdairplay: log engine: started audio cart:
> Line: 773 Cart: 10268 Cut: 1 Pos: 0 Card: 0 Stream: 0 Port: 1 Sep
> 21 23:58:06 studioc caed: PlaybackPosition - Card: 0 Stream:
> 0 Pos: 130 Handle: 169
> Sep 21 23:58:06 studioc caed: Play - Card: 0 Stream: 0 Handle:
> 169 Length: 244846 Speed: 100000 Pitch: 0 Sep 21 23:58:07 studioc
> rdairplay: log engine: finished event: Line:
> 772 Cart: 60920 Cut: 2 Card: 0 Stream: 1 Port: 0 Sep 21 23:58:07
> studioc caed: StopPlayback - Card: 0 Stream:
> 1 Handle: 168
> Sep 21 23:58:07 studioc caed: StopPlayback - Card: 0 Stream:
> 1 Handle: 168
> Sep 21 23:58:07 studioc caed: UnloadPlayback - Card: 0 Stream:
> 1 Handle: 168
> Sep 21 23:59:38 studioc rdservice: ran local maintenance routines Sep
> 22 00:02:00 studioc caed: LoadPlayback Card: 0 Stream:
> 1 Name: /var/snd/060003_001.wav Handle: 170 Sep 22 00:02:00 studioc
> rdairplay: log engine: started audio cart:
> Line: 777 Cart: 60003 Cut: 1 Pos: 0 Card: 0 Stream: 1 Port: 0 Sep
> 22 00:02:00 studioc caed: PlaybackPosition - Card: 0 Stream:
> 1 Pos: 78 Handle: 170
> Sep 22 00:02:00 studioc caed: Play - Card: 0 Stream: 1 Handle:
> 170 Length: 11481 Speed: 100000 Pitch: 0 Sep 22 00:02:11 studioc
> rdairplay: log engine: chained to log: Line:
> 778 Log: Production-0922
> Sep 22 00:02:11 studioc ripcd: received rml: "LL 1 Production-0922
> -2!" from 192.168.1.92 Sep 22 00:02:11 studioc caed: StopPlayback -
> Card: 0 Stream:
> 1 Handle: 170
> Sep 22 00:02:11 studioc caed: StopPlayback - Card: 0 Stream:
> 1 Handle: 170
> Sep 22 00:59:07 studioc rdservice: ran local maintenance routines Sep
> 22 00:59:07 studioc rdservice: ran system-wide maintenance routines
> Sep 22 00:59:08 studioc rdmaint: purged log Production-0916 Sep 22
> 01:14:45 studioc rdservice: ran local maintenance routines Sep 22
> 01:47:23 studioc rdservice: ran local maintenance routines Sep 22
> 02:03:07 studioc rdservice: ran local maintenance routines Sep 22
> 02:03:07 studioc rdservice: ran system-wide maintenance routines Sep
> 22 03:02:54 studioc rdservice: ran local maintenance routines
>
> Mark Murdock
> KAMB
> 90 E. 16th St.
> Merced, CA 95340
> (209) 723-1015
> [email protected]<mailto:[email protected]>
> Website<https://celebrationradio.com/
_______________________________________________
Rivendell-dev mailing list
[email protected]
http://caspian.paravelsystems.com/mailman/listinfo/rivendell-dev
