Project

General

Profile

Bug #6170 ยป tvh_logs_deadlock.log

rado 84, 2022-05-08 23:00

 
1

    
2
2022-04-10 19:28:20.009 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
3
2022-04-10 19:28:35.009 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
4
2022-04-10 19:28:50.009 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
5
2022-04-10 19:29:05.009 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
6
2022-04-10 19:29:20.009 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
7
2022-04-10 19:29:32.630 [  TRACE] dvr: dvr event running check for Rizzoli and Isles on Warner TV Serie HD running 1
8
2022-04-10 19:29:32.630 [  TRACE] dvr: dvr event running check for Rizzoli and Isles on Warner TV Serie HD running 3
9
2022-04-10 19:29:35.009 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
10
2022-04-10 19:29:50.009 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
11
2022-04-10 19:30:00.000 [  TRACE] dvr: entry 5df0c389f109ce42768f9f0a36dc4b1c rstart 2022-04-10;16:30:07(+0200) rstop 1970-01-01;01:00:00(+0100) - stop recording timer called
12
2022-04-10 19:30:05.009 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
13
2022-04-10 19:30:06.596 [  TRACE] dvr: 5df0c389f109ce42768f9f0a36dc4b1c - source reconfigured
14
2022-04-10 19:30:07.065 [  TRACE] dvr: dvr event running check for Live PL: Manchester City - FC Liverpool, Match of the Week, 32. Spieltag on Sky Sport 1 HD running 1
15
2022-04-10 19:30:07.065 [  DEBUG] dvr: dvr entry 5df0c389f109ce42768f9f0a36dc4b1c event Live PL: Manchester City - FC Liverpool, Match of the Week, 32. Spieltag on Sky Sport 1 HD - EPG marking stop
16
2022-04-10 19:30:07.065 [  DEBUG] dvr: dvr entry 3381173546538d8296875be3e84b8b32 event Live PL: Manchester City - FC Liverpool, Match of the Week, 32. Spieltag on Sky Sport 1 HD - EPG marking stop
17
2022-04-10 19:30:07.065 [  TRACE] dvr: dvr event running check for Live PL: What a Strike! Highlight-Show, 32. Spieltag on Sky Sport 1 HD running 3
18
2022-04-10 19:30:07.381 [WARNING] dvr: Unable to reconfigure "/recordings/2022-04-10_16-30_Sky-Sport-1-HD-1.mkv"
19
2022-04-10 19:30:07.407 [  TRACE] dvr: entry 5df0c389f109ce42768f9f0a36dc4b1c start 2022-04-10;16:30:02(+0200) stop 2022-04-10;19:30:00(+0200) - set timer
20
2022-04-10 19:30:21.570 [WARNING] mpegts: too much queued table input data (over 2MB) for SAT>IP DVB-C Tuner, discarding new
21
REASON: deadlock (src/tvh_thread.c:513)
22
mutex 0x55d97b464220 locked in: src/dvr/dvr_rec.c:1460 (thread 9223)
23
mutex 0x55d97b464220   waiting in: src/main.c:764 (thread 358)
24
mutex 0x55d97b464220   waiting in: src/notify.c:103 (thread 375)
25
mutex 0x55d97b464220   waiting in: src/main.c:703 (thread 379)
26
mutex 0x55d97b464220   waiting in: src/input/mpegts/mpegts_input.c:1807 (thread 421)
27
mutex 0x55d97b464220   waiting in: src/input/mpegts/mpegts_input.c:1807 (thread 423)
28
mutex 0x55d97b464220   waiting in: src/input/mpegts/mpegts_input.c:1807 (thread 425)
29
mutex 0x55d97b464220   waiting in: src/epggrab/module/eit.c:850 (thread 405)
30
mutex 0x55d97c960190 other in: src/input/mpegts/tsdemux.c:164 (thread 424)
31
mutex 0x55d97c56f988 other in: src/input/mpegts/mpegts_input.c:1733 (thread 424)
32
2022-04-10 19:30:24.632 [   INFO] main: Log started
33
--
34
2022-04-30 17:30:06.664 [   INFO] dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
35
2022-04-30 17:30:06.664 [   INFO] dvr:  3  H264                    1920x1080   16:9                                 
36
2022-04-30 17:30:06.664 [   INFO] dvr:  4  AC3               qaa                             48000        5.1       
37
2022-04-30 17:30:06.664 [   INFO] dvr: 11  AC3               qaa                             48000        2         
38
2022-04-30 17:30:06.664 [  TRACE] dvr: entry b2ac9af2a341cd3d297e9c6e9104c4a6 start 2022-04-30;17:30:03(+0200) stop 2022-04-30;21:15:00(+0200) - set timer
39
2022-04-30 17:30:06.664 [  TRACE] dvr: entry b2ac9af2a341cd3d297e9c6e9104c4a6 stop 2022-04-30;21:15:00(+0200) - set timer - arm
40
2022-04-30 17:30:06.664 [  TRACE] dvr: b2ac9af2a341cd3d297e9c6e9104c4a6 - muxing activated
41
2022-04-30 17:30:06.665 [  TRACE] dvr: entry b2ac9af2a341cd3d297e9c6e9104c4a6 - set state - state RECORDING rec_state RUNNING error 'OK'
42
2022-04-30 17:30:06.679 [  TRACE] dvr: entry d7850aed1d5d39139832828c12363066 start 2022-04-30;15:14:57(+0200) stop 2022-04-30;17:30:00(+0200) - set timer
43
2022-04-30 17:30:06.679 [  TRACE] dvr: entry d7850aed1d5d39139832828c12363066 stop 2022-04-30;17:30:16(+0200) - set timer - running+
44
2022-04-30 17:30:06.679 [  TRACE] dvr: entry d7850aed1d5d39139832828c12363066 stop 2022-04-30;17:30:16(+0200) - set timer - arm
45
2022-04-30 17:30:06.680 [  TRACE] dvr: entry 0d18cbf2ebe26795323cb420541aac1e - assign broadcast 67709505
46
2022-04-30 17:30:06.680 [  TRACE] dvr: entry d7850aed1d5d39139832828c12363066 - set state - state COMPLETED rec_state FINISHED error 'Source reconfigured'
47
2022-04-30 17:30:06.680 [  DEBUG] dvr: adding inotify watch to /recordings (fd=1)
48
2022-04-30 17:30:06.680 [  TRACE] dvr: entry d7850aed1d5d39139832828c12363066 when 1970-01-01;01:00:00(+0100) - retention arm
49
2022-04-30 17:30:06.680 [   INFO] dvr: "Live BL: Samstags-Konferenz, 32. Spieltag" on "Sky Sport Bundesliga 1 HD": End of program: Source reconfigured
50
2022-04-30 17:30:06.680 [  TRACE] dvr: entry d7850aed1d5d39139832828c12363066 start 2022-04-30;15:14:57(+0200) stop 2022-04-30;17:30:00(+0200) - set timer
51
2022-04-30 17:30:06.680 [  TRACE] dvr: entry d7850aed1d5d39139832828c12363066 when 1970-01-01;01:00:00(+0100) - retention arm
52
2022-04-30 17:30:06.680 [  TRACE] dvr: entry d7850aed1d5d39139832828c12363066 when 1970-01-01;01:00:00(+0100) - retention arm
53
2022-04-30 17:30:06.680 [  TRACE] dvr: entry 0d18cbf2ebe26795323cb420541aac1e - set state - state RECORDING rec_state PENDING error 'OK'
54
2022-04-30 17:30:06.680 [   INFO] dvr: "Live BL: Samstags-Konferenz, 32. Spieltag" on "Sky Sport Bundesliga 1 HD" recorder starting
55
2022-04-30 17:30:06.680 [  TRACE] dvr: entry 0d18cbf2ebe26795323cb420541aac1e original stop 2022-04-30;17:30:00(+0200) stop 2022-04-30;17:30:00(+0200) - stop timer set
56
2022-04-30 17:30:06.680 [  TRACE] dvr: entry 0d18cbf2ebe26795323cb420541aac1e start 2022-04-30;17:30:03(+0200) stop 2022-04-30;17:30:00(+0200) - set timer
57
REASON: deadlock (src/tvh_thread.c:513)
58
mutex 0x55fe67fc3220 locked in: src/dvr/dvr_rec.c:1460 (thread 422)
59
mutex 0x55fe67fc3220   waiting in: src/notify.c:103 (thread 342)
60
mutex 0x55fe67fc3220   waiting in: src/main.c:703 (thread 346)
61
mutex 0x55fe67fc3220   waiting in: src/http.c:299 (thread 446)
62
mutex 0x55fe67fc3220   waiting in: src/input/mpegts/mpegts_input.c:1807 (thread 386)
63
mutex 0x55fe67fc3220   waiting in: src/main.c:764 (thread 325)
64
2022-04-30 17:30:24.489 [   INFO] main: Log started
65
--
66
2022-05-07 15:24:30.123 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
67
2022-05-07 15:24:45.123 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
68
2022-05-07 15:25:00.000 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c rstart 2022-05-07;13:10:32(+0200) rstop 1970-01-01;01:00:00(+0100) - stop recording timer called
69
2022-05-07 15:25:00.123 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
70
2022-05-07 15:25:04.256 [  TRACE] dvr: 393f81e51c2207a34d6bf257f37d0f1c - source reconfigured
71
2022-05-07 15:25:05.817 [WARNING] dvr: Unable to reconfigure "/recordings/2022-05-07_13-00_Sky-Sport-Bundesliga-3-HD.mkv"
72
2022-05-07 15:25:05.831 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c start 2022-05-07;12:59:57(+0200) stop 2022-05-07;15:25:00(+0200) - set timer
73
2022-05-07 15:25:05.831 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c stop 2022-05-07;15:25:15(+0200) - set timer - running+
74
2022-05-07 15:25:05.831 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c stop 2022-05-07;15:25:15(+0200) - set timer - arm
75
2022-05-07 15:25:05.831 [  TRACE] dvr: entry d6636095d0d6a038a8bf0bb00e7b1844 - assign broadcast 67722423
76
2022-05-07 15:25:05.831 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c - set state - state COMPLETED rec_state FINISHED error 'Source reconfigured'
77
2022-05-07 15:25:05.831 [  DEBUG] dvr: adding inotify watch to /recordings (fd=1)
78
2022-05-07 15:25:05.831 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c when 1970-01-01;01:00:00(+0100) - retention arm
79
2022-05-07 15:25:05.831 [   INFO] dvr: "Live 2. BL: Samstags-Konferenz, 33. Spieltag" on "Sky Sport Bundesliga 3 HD": End of program: Source reconfigured
80
2022-05-07 15:25:05.831 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c start 2022-05-07;12:59:57(+0200) stop 2022-05-07;15:25:00(+0200) - set timer
81
2022-05-07 15:25:05.831 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c when 1970-01-01;01:00:00(+0100) - retention arm
82
2022-05-07 15:25:05.832 [  TRACE] dvr: entry 393f81e51c2207a34d6bf257f37d0f1c when 1970-01-01;01:00:00(+0100) - retention arm
83
2022-05-07 15:25:05.832 [  TRACE] dvr: entry d6636095d0d6a038a8bf0bb00e7b1844 - set state - state RECORDING rec_state PENDING error 'OK'
84
2022-05-07 15:25:05.832 [   INFO] dvr: "Live 2. BL: Samstags-Konferenz, 33. Spieltag" on "Sky Sport Bundesliga 3 HD" recorder starting
85
2022-05-07 15:25:05.832 [  TRACE] dvr: entry d6636095d0d6a038a8bf0bb00e7b1844 original stop 2022-05-07;15:25:00(+0200) stop 2022-05-07;15:25:00(+0200) - stop timer set
86
2022-05-07 15:25:05.832 [  TRACE] dvr: entry d6636095d0d6a038a8bf0bb00e7b1844 start 2022-05-07;15:25:02(+0200) stop 2022-05-07;15:25:00(+0200) - set timer
87
REASON: deadlock (src/tvh_thread.c:513)
88
mutex 0x564e73323220 locked in: src/dvr/dvr_rec.c:1460 (thread 23194)
89
mutex 0x564e73323220   waiting in: src/tcp.c:814 (thread 367)
90
mutex 0x564e73323220   waiting in: src/main.c:703 (thread 353)
91
mutex 0x564e73323220   waiting in: src/main.c:764 (thread 332)
92
mutex 0x564e73323220   waiting in: src/notify.c:103 (thread 349)
93
mutex 0x564e73323220   waiting in: src/input/mpegts/mpegts_input.c:1807 (thread 399)
94
mutex 0x564e73323220   waiting in: src/input/mpegts/mpegts_input.c:1807 (thread 393)
95
2022-05-07 15:25:21.624 [WARNING] mpegts: too much queued table input data (over 2MB) for SAT>IP DVB-C Tuner, discarding new
96
2022-05-07 15:25:23.481 [   INFO] main: Log started
97
--
98
2022-05-07 21:14:29.630 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
99
2022-05-07 21:14:44.630 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
100
2022-05-07 21:14:59.630 [  TRACE] dvr: checking free and used disk space for config "Default profile" : OK
101
2022-05-07 21:15:00.000 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac rstart 2022-05-07;17:30:06(+0200) rstop 2022-05-07;15:25:41(+0200) - stop recording timer called
102
2022-05-07 21:15:03.310 [  TRACE] dvr: 948e91c9d1254f2ae8c0198214dff5ac - source reconfigured
103
2022-05-07 21:15:04.346 [WARNING] dvr: Unable to reconfigure "/recordings/2022-05-07_17-30_Sky-Sport-Bundesliga-1-HD.mkv"
104
2022-05-07 21:15:04.380 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac start 2022-05-07;17:29:57(+0200) stop 2022-05-07;21:15:00(+0200) - set timer
105
2022-05-07 21:15:04.380 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac stop 2022-05-07;21:15:14(+0200) - set timer - running+
106
2022-05-07 21:15:04.380 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac stop 2022-05-07;21:15:14(+0200) - set timer - arm
107
2022-05-07 21:15:04.380 [  TRACE] dvr: entry a1e143de8652cad3c88ee472019ad089 - assign broadcast 67709458
108
2022-05-07 21:15:04.380 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac - set state - state COMPLETED rec_state FINISHED error 'Source reconfigured'
109
2022-05-07 21:15:04.380 [  DEBUG] dvr: adding inotify watch to /recordings (fd=1)
110
2022-05-07 21:15:04.380 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac when 2022-05-21;21:15:00(+0200) - retention arm
111
2022-05-07 21:15:04.380 [   INFO] dvr: "Live BL: Hertha BSC - 1. FSV Mainz 05, tipico Topspiel der Woche, 33. Spieltag" on "Sky Sport Bundesliga 1 HD": End of program: Source reconfigured
112
2022-05-07 21:15:04.380 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac start 2022-05-07;17:29:57(+0200) stop 2022-05-07;21:15:00(+0200) - set timer
113
2022-05-07 21:15:04.380 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac when 2022-05-21;21:15:00(+0200) - retention arm
114
2022-05-07 21:15:04.380 [  TRACE] dvr: entry 948e91c9d1254f2ae8c0198214dff5ac when 2022-05-21;21:15:00(+0200) - retention arm
115
2022-05-07 21:15:04.380 [  TRACE] dvr: entry a1e143de8652cad3c88ee472019ad089 - set state - state RECORDING rec_state PENDING error 'OK'
116
2022-05-07 21:15:04.380 [   INFO] dvr: "Live BL: Hertha BSC - 1. FSV Mainz 05, tipico Topspiel der Woche, 33. Spieltag" on "Sky Sport Bundesliga 1 HD" recorder starting
117
2022-05-07 21:15:04.380 [  TRACE] dvr: entry a1e143de8652cad3c88ee472019ad089 original stop 2022-05-07;21:15:00(+0200) stop 2022-05-07;21:15:00(+0200) - stop timer set
118
2022-05-07 21:15:04.380 [  TRACE] dvr: entry a1e143de8652cad3c88ee472019ad089 start 2022-05-07;21:15:01(+0200) stop 2022-05-07;21:15:00(+0200) - set timer
119
REASON: deadlock (src/tvh_thread.c:513)
120
mutex 0x557092287220 locked in: src/dvr/dvr_rec.c:1460 (thread 24662)
121
mutex 0x557092287220   waiting in: src/tcp.c:814 (thread 23959)
122
mutex 0x557092287220   waiting in: src/main.c:703 (thread 23945)
123
mutex 0x557092287220   waiting in: src/main.c:764 (thread 23937)
124
mutex 0x557092287220   waiting in: src/input/mpegts/mpegts_input.c:1807 (thread 23989)
125
mutex 0x557092287220   waiting in: src/notify.c:103 (thread 23941)
126
mutex 0x557092287220   waiting in: src/input/mpegts/mpegts_input.c:1807 (thread 23985)
127
2022-05-07 21:15:20.196 [WARNING] mpegts: too much queued table input data (over 2MB) for SAT>IP DVB-C Tuner, discarding new
128
2022-05-07 21:15:21.973 [   INFO] main: Log started
    (1-1/1)