RTAI 5.0.1 calibrate program: BUG: unable to handle kernel paging request at 00007ffe78e450f0

Vivian McPhail <[email protected]> Fri, 15 Sep 2017 13:45:50 +1200
Newsgroups gmane.linux.real-time.rtai
Message-ID <CAGSp+B62YgBB6xR+DapYgxzwcXpMYBjSv-vsyvU0ow+sJQ-Kcg@mail.gmail.com>
Dear list,

I have an Intel i3 7100 dual core CPU system that reports itself as having
4 cores (hyperthreaded).

I have patched the kernel (4.4.43) with rtai path from rtai-5.0.1

After make menuconfig in rtai-5.0.1 directory and selecting number of CPUs
as 2 and leaving all else unchanged, after make && make install, I get the
following:

sc-qi4 /usr/realtime/testsuite/kern/latency # ./run
*
*
* Type ^C to stop this application.
*
*
insmod: ERROR: could not insert module /usr/realtime/modules/rtai_hal.ko:
Operation not permitted
ERROR: cannot load /usr/realtime/modules/rtai_hal.ko
insmod: ERROR: could not insert module /usr/realtime/modules/latency_rt.ko:
Unknown symbol in module
ERROR: cannot load /usr/realtime/modules/latency_rt.ko
Error opening /dev/rtf1

dmesg output:
<snip>
[  235.688089] RTAI[hal]: RTAI CONFIGURED WITH LESS THAN NUM ONLINE CPUS.
[  235.727326] latency_rt: Unknown symbol nano2count (err 0)
[  235.727329] latency_rt: Unknown symbol rt_get_time (err 0)
[  235.727330] latency_rt: Unknown symbol rt_task_make_periodic (err 0)
[  235.727331] latency_rt: Unknown symbol rt_set_runnable_on_cpus (err 0)
[  235.727332] latency_rt: Unknown symbol start_rt_timer (err 0)
[  235.727333] latency_rt: Unknown symbol rt_task_delete (err 0)
[  235.727334] latency_rt: Unknown symbol rtf_put (err 0)
[  235.727336] latency_rt: Unknown symbol stop_rt_timer (err 0)
[  235.727336] latency_rt: Unknown symbol rtf_destroy (err 0)
[  235.727337] latency_rt: Unknown symbol rt_get_cpu_time_ns (err 0)
[  235.727338] latency_rt: Unknown symbol rt_linux_use_fpu (err 0)
[  235.727340] latency_rt: Unknown symbol rt_set_oneshot_mode (err 0)
[  235.727341] latency_rt: Unknown symbol rtf_create (err 0)
[  235.727342] latency_rt: Unknown symbol rt_task_init (err 0)
[  235.727343] latency_rt: Unknown symbol count2nano (err 0)
[  235.727344] latency_rt: Unknown symbol rt_set_periodic_mode (err 0)
[  235.727346] latency_rt: Unknown symbol rt_task_wait_period (err 0)

So I now make menuconfig and set number of CPUs to 4

sc-qi4 /usr/realtime/testsuite/kern/latency # ./run
*
*
* Type ^C to stop this application.
*
*
RTAI Testsuite - KERNEL space latency test (output data in nanoseconds)

*** latency verification tool with RTAI own real time kernel tasks ***
***    period = 100000 (ns),  avrgtime = 1 (s)    ***

RTH|    lat min|    ovl min|    lat avg|    lat max|    ovl max|   overruns
RTD|          0|          0|       1150|     160725|     160725|         73
RTD|          0|          0|       1187|     159405|     160725|        113
RTD|          0|          0|       1152|     165728|     165728|        152
RTD|          0|          0|       1162|     163048|     165728|        190
RTD|          0|          0|       1180|     164419|     165728|        231
RTD|          0|          0|       1165|     163306|     165728|        267
RTD|          0|          0|       1158|     165418|     165728|        304
RTD|          0|          0|       1172|     161802|     165728|        343
RTD|          0|          0|       1154|     161838|     165728|        380
RTD|          0|          0|       1162|     166058|     166058|        416
RTD|          0|          0|       1148|     162495|     166058|        453
RTD|          0|          0|       1272|    1259694|    1259694|        502
RTD|          0|          0|       1267|    1058307|    1259694|        549
RTD|          0|          0|       1226|     859009|    1259694|        594
RTD|          0|          0|       1191|     560218|    1259694|        635
RTD|          0|          0|       1188|     354817|    1259694|        676
RTD|          0|          0|       1156|     161124|    1259694|        713
RTD|          0|          0|       1165|     163014|    1259694|        752
RTD|          0|          0|       1183|     174834|    1259694|        792
RTD|          0|          0|       1159|     160292|    1259694|        830
^CRTD|          0|          0|       1159|     160292|    1259694|
830
rmmod: ERROR: Module latency_rt is in use
rmmod: ERROR: Module rtai_fifos is in use
rmmod: ERROR: Module rtai_sched is in use
rmmod: ERROR: Module rtai_hal is in use

so then I try to run the calibration program:

sc-qi4 /usr/realtime/calibration # ./calibrate
Killed

<snip>
[  959.562388] RTAI[hal]: mounted. ISOL_CPUS_MASK: 0.
[  959.562390] SYSINFO - # CPUs: 4, TIMER NAME: 'lapic', TIMER IRQ: 4354,
TIMER FREQ: 115510001, CLOCK NAME: 'tsc', CLOCK FREQ: 3696332000, CPU FREQ:
3696332000, LINUX TIMER IRQ: 4354.
[  959.570145] RTAI[malloc]: global heap size = 2097152 bytes, <BSD>.
[  959.570167] , kstacks pool size = 524288 bytes.
[  959.570168] RTAI[sched]: hard timer type/freq = lapic/115510001(Hz);
timing: ONESHOT; linear timed lists.
[  959.570169] RTAI[sched]: Linux timer freq = 250 (Hz), TimeBase freq =
3696332000 hz.
[  959.570170] RTAI[sched]: timer setup = 62 ns, resched latency = 0 ns.
[  959.570435] BUG: unable to handle kernel paging request at
00007ffe78e450f0
[  959.570939] IP: [<ffffffffa01aa785>] rtai_lxrt_invoke+0xda5/0x1b30
[rtai_sched]
[  959.571356] PGD 46c2ae067 PUD 46c13a067 PMD 46a06c067 PTE
800000045d255067
[  959.571772] Oops: 0001 [#1] SMP
[  959.572177] Modules linked in: rtai_sched(O+) rtai_hal(O)
x86_pkg_temp_thermal sha256_generic igb
[  959.572605] CPU: 3 PID: 23288 Comm: calibrate Tainted: G           O
4.4.43-rtai #15
[  959.573030] Hardware name: Supermicro Super Server/X11SSA-F, BIOS 2.0a
03/07/2017
[  959.573459] task: ffff88046a338bc0 ti: ffff88046c330000 task.ti:
ffff88046c330000
[  959.573896] RIP: 0010:[<ffffffffa01aa785>]  [<ffffffffa01aa785>]
rtai_lxrt_invoke+0xda5/0x1b30 [rtai_sched]
[  959.574349] RSP: 0018:ffff88046c333e90  EFLAGS: 00010206
[  959.574796] RAX: 0000000000000407 RBX: ffff88046c333f58 RCX:
00007f16b91ce5c9
[  959.575248] RDX: 0000000000000000 RSI: 00007ffe78e450f0 RDI:
0000000000407818
[  959.575703] RBP: ffff88046c333f10 R08: 0000000000000000 R09:
000000006562b026
[  959.576152] R10: 00007ffe78e450a8 R11: 0000000000000206 R12:
00007ffe78e450f0
[  959.576605] R13: 0000000000407818 R14: 0000000000000000 R15:
0000000000000000
[  959.577060] FS:  00007f16b9ab9700(0000) GS:ffff88047fd80000(0000)
knlGS:0000000000000000
[  959.577514] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  959.577961] CR2: 00007ffe78e450f0 CR3: 0000000469ede000 CR4:
00000000003406e0
[  959.578411] I-pipe domain Linux
[  959.578852] Stack:
[  959.579293]  00000000ffffff9c 0000000000000010 0000000000000000
ffff880075760d80
[  959.579757]  ffff88046c333ec0 ffffffff81089ec2 ffff88046c333ed0
ffffffff8105f365
[  959.580219]  ffff88046c333f38 ffffffff810fad4f ffff880075760500
ffff88046c333f58
[  959.580678] Call Trace:
[  959.581137]  [<ffffffff81089ec2>] ? call_rcu_sched+0x12/0x20
[  959.581597]  [<ffffffff8105f365>] ? __put_cred+0x35/0x40
[  959.582056]  [<ffffffff810fad4f>] ? SyS_faccessat+0x1bf/0x200
[  959.582519]  [<ffffffffa01a8876>] lxrt_intercept_syscall+0xb6/0x180
[rtai_sched]
[  959.582983]  [<ffffffff810ab022>] __ipipe_notify_syscall+0x12/0x20
[  959.583448]  [<ffffffff815a592c>] pipeline_syscall+0x9/0x16
[  959.583916] Code: e1 07 89 c8 c1 f9 1f f7 ea c1 fa 05 29 ca 48 63 f2 31
d2 48 89 b3 68 11 00 00 e8 c7 68 00 00 48 89 83 58 11 00 00 e9 dd f4 ff ff
<48> 8b 1e 8d 90 18 fc ff ff 83 fa 20 48 89 d9 0f 87 e2 04 00 00
[  959.584963] RIP  [<ffffffffa01aa785>] rtai_lxrt_invoke+0xda5/0x1b30
[rtai_sched]
[  959.585476]  RSP <ffff88046c333e90>
[  959.586158] CR2: 00007ffe78e450f0
[  959.589612] ---[ end trace a7110ad5d5eae434 ]---
[  959.589649] ------------[ cut here ]------------
[  959.589653] WARNING: CPU: 3 PID: 23288 at kernel/softirq.c:150
__local_bh_enable_ip+0x7c/0xa0()
[  959.589654] Modules linked in: rtai_sched(O+) rtai_hal(O)
x86_pkg_temp_thermal sha256_generic igb
[  959.589658] CPU: 3 PID: 23288 Comm: calibrate Tainted: G      D    O
4.4.43-rtai #15
[  959.589659] Hardware name: Supermicro Super Server/X11SSA-F, BIOS 2.0a
03/07/2017
[  959.589660]  0000000000000000 ffff88046c333b60 ffffffff811f4341
0000000000000000
[  959.589661]  ffffffff81727e51 ffff88046c333b98 ffffffff81044ad8
0000000000000200
[  959.589663]  ffffffff81828820 ffff880469f81180 0000000000000009
ffff880469f811e8
[  959.589665] Call Trace:
[  959.589667]  [<ffffffff811f4341>] dump_stack+0x96/0xc5
[  959.589668]  [<ffffffff81044ad8>] warn_slowpath_common+0x78/0xb0
[  959.589670]  [<ffffffff81044bc5>] warn_slowpath_null+0x15/0x20
[  959.589671]  [<ffffffff81047dbc>] __local_bh_enable_ip+0x7c/0xa0
[  959.589673]  [<ffffffff815a5485>] _raw_write_unlock_bh+0x15/0x20
[  959.589674]  [<ffffffff815a5499>] _raw_spin_unlock_bh+0x9/0x10
[  959.589676]  [<ffffffff810a64d8>] cgroup_exit+0x48/0x60
[  959.589677]  [<ffffffff8104628c>] do_exit+0x32c/0xa40
[  959.589679]  [<ffffffff8100632a>] oops_end+0x7a/0xa0
[  959.589681]  [<ffffffff8103a645>] no_context+0x135/0x370
[  959.589682]  [<ffffffff8103a98a>] __bad_area_nosemaphore+0x10a/0x220
[  959.589684]  [<ffffffff810d9ed5>] ? list_lru_add+0x15/0x50
[  959.589685]  [<ffffffff8103aaae>] bad_area_nosemaphore+0xe/0x10
[  959.589686]  [<ffffffff8103ad02>] __do_page_fault+0x82/0x370
[  959.589688]  [<ffffffff81104cd8>] ? terminate_walk+0xb8/0xd0
[  959.589690]  [<ffffffff8103b075>] do_page_fault+0x55/0x90
[  959.589691]  [<ffffffff815a7682>] page_fault+0x22/0x60
[  959.589697]  [<ffffffffa01aa785>] ? rtai_lxrt_invoke+0xda5/0x1b30
[rtai_sched]
[  959.589699]  [<ffffffff81089ec2>] ? call_rcu_sched+0x12/0x20
[  959.589700]  [<ffffffff8105f365>] ? __put_cred+0x35/0x40
[  959.589702]  [<ffffffff810fad4f>] ? SyS_faccessat+0x1bf/0x200
[  959.589705]  [<ffffffffa01a8876>] lxrt_intercept_syscall+0xb6/0x180
[rtai_sched]
[  959.589706]  [<ffffffff810ab022>] __ipipe_notify_syscall+0x12/0x20
[  959.589707]  [<ffffffff815a592c>] pipeline_syscall+0x9/0x16
[  959.589708] ---[ end trace a7110ad5d5eae435 ]---
[  959.589722] USERMODE CHECK: ERROR.
[  959.589723] USERMODE CHECK PROVIDED (ns): KernelLatency 0, UserLatency 0.
[  959.589724] FINAL CALIBRATION SUMMARY (ns): KernelLatency 0, UserLatency
0.
[ 1036.125661] BUG: unable to handle kernel paging request at
00007ffed16a44d0
[ 1036.126181] IP: [<ffffffffa01aa785>] rtai_lxrt_invoke+0xda5/0x1b30
[rtai_sched]
[ 1036.126700] PGD 46b8d4067 PUD 469e8a067 PMD 46a1f1067 PTE
80000004566c6067
[ 1036.127390] Oops: 0001 [#2] SMP
[ 1036.127906] Modules linked in: latency_rt(O) rtai_fifos(O) rtai_sched(O)
rtai_hal(O) x86_pkg_temp_thermal sha256_generic igb
[ 1036.128446] CPU: 3 PID: 23428 Comm: calibrate Tainted: G      D W  O
4.4.43-rtai #15
[ 1036.128980] Hardware name: Supermicro Super Server/X11SSA-F, BIOS 2.0a
03/07/2017
[ 1036.129522] task: ffff88046ab68000 ti: ffff88046c09c000 task.ti:
ffff88046c09c000
[ 1036.130071] RIP: 0010:[<ffffffffa01aa785>]  [<ffffffffa01aa785>]
rtai_lxrt_invoke+0xda5/0x1b30 [rtai_sched]
[ 1036.130635] RSP: 0018:ffff88046c09fe90  EFLAGS: 00010206
[ 1036.131195] RAX: 0000000000000407 RBX: ffff88046c09ff58 RCX:
00007f90fb5305c9
[ 1036.131763] RDX: 0000000000000000 RSI: 00007ffed16a44d0 RDI:
0000000000407818
[ 1036.132327] RBP: ffff88046c09ff10 R08: 0000000000000000 R09:
000000006562b026
[ 1036.132894] R10: 00007ffed16a4488 R11: 0000000000000202 R12:
00007ffed16a44d0
[ 1036.133462] R13: 0000000000407818 R14: 0000000000000000 R15:
0000000000000000
[ 1036.134032] FS:  00007f90fbe1b700(0000) GS:ffff88047fd80000(0000)
knlGS:0000000000000000
[ 1036.134605] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1036.135178] CR2: 00007ffed16a44d0 CR3: 000000046a1a6000 CR4:
00000000003406e0
[ 1036.135762] I-pipe domain Linux
[ 1036.136342] Stack:
[ 1036.136916]  00000000ffffff9c 0000000000000010 0000000000000000
ffff880469581e80
[ 1036.137512]  ffff88046c09fec0 ffffffff81089ec2 ffff88046c09fed0
ffffffff8105f365
[ 1036.138111]  ffff88046c09ff38 ffffffff810fad4f ffff880469e45180
ffff88046c09ff58
[ 1036.138708] Call Trace:
[ 1036.139303]  [<ffffffff81089ec2>] ? call_rcu_sched+0x12/0x20
[ 1036.139908]  [<ffffffff8105f365>] ? __put_cred+0x35/0x40
[ 1036.140506]  [<ffffffff810fad4f>] ? SyS_faccessat+0x1bf/0x200
[ 1036.141105]  [<ffffffffa01a8876>] lxrt_intercept_syscall+0xb6/0x180
[rtai_sched]
[ 1036.141715]  [<ffffffff810ab022>] __ipipe_notify_syscall+0x12/0x20
[ 1036.142321]  [<ffffffff815a592c>] pipeline_syscall+0x9/0x16
[ 1036.142919] Code: e1 07 89 c8 c1 f9 1f f7 ea c1 fa 05 29 ca 48 63 f2 31
d2 48 89 b3 68 11 00 00 e8 c7 68 00 00 48 89 83 58 11 00 00 e9 dd f4 ff ff
<48> 8b 1e 8d 90 18 fc ff ff 83 fa 20 48 89 d9 0f 87 e2 04 00 00
[ 1036.144391] RIP  [<ffffffffa01aa785>] rtai_lxrt_invoke+0xda5/0x1b30
[rtai_sched]
[ 1036.145016]  RSP <ffff88046c09fe90>
[ 1036.145629] CR2: 00007ffed16a44d0
[ 1036.146237] ---[ end trace a7110ad5d5eae436 ]---

Thanks in advance.

Vivian
-- 
Vivian McPhail
PhD Candidate
Department of Physics
University of Auckland

_______________________________________________
Rtai mailing list
[email protected]
https://mail.rtai.org/cgi-bin/mailman/listinfo/rtai