RE: [Linux-hls-devel] Processes freezing up

[Tony L. <tl...@au...>]

Hi Luca,

Sorry for the late reply - our mail server is finally working correctly
(I hope!).

>Does your machine crash even without using the RTC=20
Yes. I sent the first message at the end of the day after only running a
couple of tests, but I then ran more tests the next day which showed the
problem to occur even without the RTC. Sorry about the confusion - I
thought the first email was lost but our mail server sent it out a day
late!

>Was the test performed with or without the kernel preemption patch?
Yes, we have been using the kernel preemption patch. I did plan to
re-test without this patch but I've been busy with other work.

>If I understand well, the "HLS ERROR: Scheduler xxx posted a timer
twice" is not the first error in your log... Is my understanding
correct?

Yes, here is the log from when the hls_module was inserted:

Sep  9 09:36:31 wally kernel: HLS MP initializing (HLS_DBG_PRINT_LEVEL =
=3D
1).
Sep  9 09:36:31 wally kernel: [1643284912], 803 : sched 'ROOT'
registered in slot 0
Sep  9 09:36:31 wally kernel: [1718493520], 803 : sched 'JOIN'
registered in slot 1
Sep  9 09:36:31 wally kernel: [1793690865], 803 : sched 'TH' registered
in slot 2
Sep  9 09:36:31 wally kernel: [1866161544], 803 : sched 'RR' registered
in slot 3
Sep  9 09:36:31 wally kernel: [1938629664], 803 : sched 'PS' registered
in slot 4
Sep  9 09:36:31 wally kernel: [2011100543], 803 : sched 'RES' registered
in slot 5
Sep  9 09:36:31 wally kernel: already PRIVATE_DATA !=3D NULL???
Sep  9 09:36:31 wally kernel: already PRIVATE_DATA !=3D NULL???
Sep  9 09:36:36 wally kernel: hls_ctl: Moving to res1
Sep  9 09:36:36 wally kernel:     ...and setting the parameters!
Sep  9 09:36:52 wally kernel: HLS ERROR: Task 756 has rt_priority =3D =
100
and state =3D 1
Sep  9 09:36:52 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[505]
Sep  9 09:36:52 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[505]
Sep  9 09:36:52 wally kernel: HLS ERROR: Task 756 has rt_priority =3D =
100
and state =3D 1
Sep  9 09:36:52 wally kernel: HLS ERROR: Task 756 has rt_priority =3D =
100
and state =3D 1
Sep  9 09:36:52 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[505]
Sep  9 09:36:52 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[505]
Sep  9 09:36:52 wally kernel: HLS ERROR: Task 756 has rt_priority =3D =
100
and state =3D 1
Sep  9 09:36:52 wally kernel: HLS ERROR!!! Task state changed during the
hook??? 0 !=3D 1!!! Correcting...

Lots of the above messages, then ...

Sep  9 09:38:42 wally kernel: HLS ERROR: Task 635 has rt_priority =3D =
100
and state =3D 1
Sep  9 09:38:42 wally kernel: HLS ERROR: Task 635 has rt_priority =3D =
100
and state =3D 1
Sep  9 09:38:42 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[505]
Sep  9 09:38:42 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[505]
Sep  9 09:38:42 wally kernel: HLS ERROR: Task 635 has rt_priority =3D =
100
and state =3D 1
Sep  9 09:38:42 wally last message repeated 34 times
Sep  9 09:39:38 wally kernel: hls_ctl: Moving to res1
Sep  9 09:39:38 wally kernel:     ...and setting the parameters!
Sep  9 09:39:47 wally kernel: HLS ERROR: Scheduler res1 posted a timer
twice!!! WAI =3D 0
Sep  9 09:40:04 wally last message repeated 2 times
Sep  9 09:40:05 wally kernel: HLS ERROR: Task 1130 has rt_priority =3D =
100
and state =3D 1

>Is the HLS module failing when compiled with CLI=3D1 CREATE=3D1
INT_SCHED=3D1 DEBUG=3D0 MULDIV=3D1?
I've just tried this and it builds OK. My machine still froze - here's
the output ... (I don't think that anything new was logged)

Sep 12 13:03:02 wally kernel: HLS MP initializing (no debugging).
Sep 12 13:03:02 wally kernel: already PRIVATE_DATA !=3D NULL???
Sep 12 13:03:02 wally kernel: already PRIVATE_DATA !=3D NULL???
Sep 12 13:04:26 wally kernel: hls_ctl: Moving to res1
Sep 12 13:04:26 wally kernel:     ...and setting the parameters!
Sep 12 13:04:49 wally kernel: HLS ERROR: Task 1105 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:49 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[498]
Sep 12 13:04:49 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[498]
Sep 12 13:04:49 wally kernel: HLS ERROR: Task 1105 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:49 wally kernel: HLS ERROR: Task 1105 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:49 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[498]
Sep 12 13:04:49 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[498]
Sep 12 13:04:49 wally kernel: HLS ERROR: Task 1105 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:49 wally kernel: HLS ERROR!!! Task state changed during the
hook??? 0 !=3D 1!!! Correcting...
Sep 12 13:04:49 wally kernel: HLS ERROR: Task 498 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:49 wally kernel: HLS ERROR: Task 498 has rt_priority =3D =
100
and state =3D 0
Sep 12 13:04:52 wally kernel: HLS ERROR: Task 1105 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:52 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[498]
Sep 12 13:04:52 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[498]
Sep 12 13:04:52 wally kernel: HLS ERROR: Task 1105 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:52 wally kernel: HLS ERROR: Task 1105 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:52 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[498]
Sep 12 13:04:52 wally kernel: HLSUnblockThreadHook --- WAI =3D 5    =
[498]
Sep 12 13:04:52 wally kernel: HLS ERROR: Task 1105 has rt_priority =3D =
100
and state =3D 1
Sep 12 13:04:52 wally kernel: HLS ERROR!!! Task state changed during the
hook??? 0 !=3D 1!!! Correcting...
... etc ...


Thanks,
Tony