FIM crashes with new kernel 4.15.0-193

[James Elliott]

In Ubuntu 18.04 (AMD64), FIM 0.5~rc3=2build1 crashes while running the following command:
'fim -d /dev/fb0 -q -a <some image> -c "sleep 15;quit"

The following logs are produced by the kernel.
Sep 29 16:06:02 hostname-removed kernel: [ 363.476568] INFO: task kworker/u8:0:5 blocked for more than 120 seconds.
Sep 29 16:06:02 hostname-removed kernel: [ 363.476572] Tainted: G OE 4.15.0-193-generic #204-Ubuntu
Sep 29 16:06:02 hostname-removed kernel: [ 363.476573] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 29 16:06:02 hostname-removed kernel: [ 363.476576] kworker/u8:0 D 0 5 2 0x80000000
Sep 29 16:06:02 hostname-removed kernel: [ 363.476590] Workqueue: events_unbound flush_to_ldisc
Sep 29 16:06:02 hostname-removed kernel: [ 363.476592] Call Trace:
Sep 29 16:06:02 hostname-removed kernel: [ 363.476603] __schedule+0x24e/0x890
Sep 29 16:06:02 hostname-removed kernel: [ 363.476607] schedule+0x2c/0x80
Sep 29 16:06:02 hostname-removed kernel: [ 363.476610] schedule_timeout+0x1cf/0x370
Sep 29 16:06:02 hostname-removed kernel: [ 363.476617] ? ahci_qc_prep+0x153/0x170 [libahci]
Sep 29 16:06:02 hostname-removed kernel: [ 363.476623] ? find_next_bit+0xb/0x10
Sep 29 16:06:02 hostname-removed kernel: [ 363.476628] __down+0x91/0xe0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476634] down+0x41/0x50
Sep 29 16:06:02 hostname-removed kernel: [ 363.476636] ? down+0x41/0x50
Sep 29 16:06:02 hostname-removed kernel: [ 363.476639] console_lock+0x1a/0x40
Sep 29 16:06:02 hostname-removed kernel: [ 363.476643] do_con_write.part.19+0x39/0x9c0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476647] ? __wake_up_common_lock+0x8e/0xc0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476651] con_write+0x5c/0x60
Sep 29 16:06:02 hostname-removed kernel: [ 363.476656] do_output_char+0x166/0x1d0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476659] __process_echoes+0xfc/0x280
Sep 29 16:06:02 hostname-removed kernel: [ 363.476661] n_tty_receive_buf_common+0x7a6/0xc10
Sep 29 16:06:02 hostname-removed kernel: [ 363.476665] n_tty_receive_buf2+0x14/0x20
Sep 29 16:06:02 hostname-removed kernel: [ 363.476667] tty_ldisc_receive_buf+0x25/0x60
Sep 29 16:06:02 hostname-removed kernel: [ 363.476670] tty_port_default_receive_buf+0x49/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476673] flush_to_ldisc+0x97/0xf0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476677] process_one_work+0x1de/0x420
Sep 29 16:06:02 hostname-removed kernel: [ 363.476680] worker_thread+0x32/0x410
Sep 29 16:06:02 hostname-removed kernel: [ 363.476684] kthread+0x121/0x140
Sep 29 16:06:02 hostname-removed kernel: [ 363.476686] ? process_one_work+0x420/0x420
Sep 29 16:06:02 hostname-removed kernel: [ 363.476689] ? kthread_create_worker_on_cpu+0x70/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476693] ret_from_fork+0x35/0x40
Sep 29 16:06:02 hostname-removed kernel: [ 363.476702] INFO: task kworker/2:1:36 blocked for more than 120 seconds.
Sep 29 16:06:02 hostname-removed kernel: [ 363.476704] Tainted: G OE 4.15.0-193-generic #204-Ubuntu
Sep 29 16:06:02 hostname-removed kernel: [ 363.476705] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 29 16:06:02 hostname-removed kernel: [ 363.476706] kworker/2:1 D 0 36 2 0x80000000
Sep 29 16:06:02 hostname-removed kernel: [ 363.476713] Workqueue: events console_callback
Sep 29 16:06:02 hostname-removed kernel: [ 363.476714] Call Trace:
Sep 29 16:06:02 hostname-removed kernel: [ 363.476719] __schedule+0x24e/0x890
Sep 29 16:06:02 hostname-removed kernel: [ 363.476723] schedule+0x2c/0x80
Sep 29 16:06:02 hostname-removed kernel: [ 363.476725] schedule_timeout+0x1cf/0x370
Sep 29 16:06:02 hostname-removed kernel: [ 363.476727] ? __switch_to_asm+0x35/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476729] ? __switch_to_asm+0x41/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476731] ? __switch_to_asm+0x35/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476733] ? __switch_to_asm+0x41/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476735] ? __switch_to_asm+0x35/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476737] ? __switch_to_asm+0x41/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476739] ? __switch_to_asm+0x35/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476741] ? __switch_to_asm+0x41/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476745] __down+0x91/0xe0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476748] ? syscall_return_via_sysret+0x16/0x76
Sep 29 16:06:02 hostname-removed kernel: [ 363.476750] ? __switch_to_asm+0x35/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476752] ? __switch_to_asm+0x35/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476755] down+0x41/0x50
Sep 29 16:06:02 hostname-removed kernel: [ 363.476757] ? down+0x41/0x50
Sep 29 16:06:02 hostname-removed kernel: [ 363.476760] console_lock+0x1a/0x40
Sep 29 16:06:02 hostname-removed kernel: [ 363.476763] console_callback+0x2b/0x170
Sep 29 16:06:02 hostname-removed kernel: [ 363.476766] ? __schedule+0x256/0x890
Sep 29 16:06:02 hostname-removed kernel: [ 363.476769] ? pwq_dec_nr_in_flight+0x49/0xa0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476772] process_one_work+0x1de/0x420
Sep 29 16:06:02 hostname-removed kernel: [ 363.476774] worker_thread+0x32/0x410
Sep 29 16:06:02 hostname-removed kernel: [ 363.476778] kthread+0x121/0x140
Sep 29 16:06:02 hostname-removed kernel: [ 363.476781] ? process_one_work+0x420/0x420
Sep 29 16:06:02 hostname-removed kernel: [ 363.476784] ? kthread_create_worker_on_cpu+0x70/0x70
Sep 29 16:06:02 hostname-removed kernel: [ 363.476787] ret_from_fork+0x35/0x40
Sep 29 16:06:02 hostname-removed kernel: [ 363.476859] INFO: task fim:2535 blocked for more than 120 seconds.
Sep 29 16:06:02 hostname-removed kernel: [ 363.476861] Tainted: G OE 4.15.0-193-generic #204-Ubuntu
Sep 29 16:06:02 hostname-removed kernel: [ 363.476862] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 29 16:06:02 hostname-removed kernel: [ 363.476863] fim D 0 2535 2529 0x00000080
Sep 29 16:06:02 hostname-removed kernel: [ 363.476866] Call Trace:
Sep 29 16:06:02 hostname-removed kernel: [ 363.476870] __schedule+0x24e/0x890
Sep 29 16:06:02 hostname-removed kernel: [ 363.476874] schedule+0x2c/0x80
Sep 29 16:06:02 hostname-removed kernel: [ 363.476877] schedule_preempt_disabled+0xe/0x10
Sep 29 16:06:02 hostname-removed kernel: [ 363.476880] __mutex_lock.isra.5+0x276/0x4e0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476883] ? set_termios+0x162/0x200
Sep 29 16:06:02 hostname-removed kernel: [ 363.476887] __mutex_lock_slowpath+0x13/0x20
Sep 29 16:06:02 hostname-removed kernel: [ 363.476890] ? __mutex_lock_slowpath+0x13/0x20
Sep 29 16:06:02 hostname-removed kernel: [ 363.476893] mutex_lock+0x2f/0x40
Sep 29 16:06:02 hostname-removed kernel: [ 363.476898] lock_fb_info+0x1b/0x40
Sep 29 16:06:02 hostname-removed kernel: [ 363.476900] do_fb_ioctl+0x5c6/0x680
Sep 29 16:06:02 hostname-removed kernel: [ 363.476903] ? n_tty_ioctl_helper+0x29/0x130
Sep 29 16:06:02 hostname-removed kernel: [ 363.476906] ? n_tty_ioctl_helper+0x29/0x130
Sep 29 16:06:02 hostname-removed kernel: [ 363.476910] ? do_wait_intr_irq+0x90/0x90
Sep 29 16:06:02 hostname-removed kernel: [ 363.476913] fb_ioctl+0x33/0x40
Sep 29 16:06:02 hostname-removed kernel: [ 363.476915] ? fb_ioctl+0x33/0x40
Sep 29 16:06:02 hostname-removed kernel: [ 363.476920] do_vfs_ioctl+0xa8/0x630
Sep 29 16:06:02 hostname-removed kernel: [ 363.476925] ? __audit_syscall_entry+0xbc/0x110
Sep 29 16:06:02 hostname-removed kernel: [ 363.476929] ? syscall_trace_enter+0x1da/0x2d0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476933] SyS_ioctl+0x79/0x90
Sep 29 16:06:02 hostname-removed kernel: [ 363.476937] do_syscall_64+0x73/0x130
Sep 29 16:06:02 hostname-removed kernel: [ 363.476940] entry_SYSCALL_64_after_hwframe+0x41/0xa6
Sep 29 16:06:02 hostname-removed kernel: [ 363.476943] RIP: 0033:0x7f8dd256e217
Sep 29 16:06:02 hostname-removed kernel: [ 363.476944] RSP: 002b:00007ffd8b2d2218 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
Sep 29 16:06:02 hostname-removed kernel: [ 363.476947] RAX: ffffffffffffffda RBX: 00005568ebd535f0 RCX: 00007f8dd256e217
Sep 29 16:06:02 hostname-removed kernel: [ 363.476949] RDX: 00005568ebd5709c RSI: 0000000000004601 RDI: 0000000000000003
Sep 29 16:06:02 hostname-removed kernel: [ 363.476951] RBP: 0000000000000000 R08: ffffffffffffff58 R09: 00005568ebad40dc
Sep 29 16:06:02 hostname-removed kernel: [ 363.476952] R10: 0000000000000010 R11: 0000000000000246 R12: 00007ffd8b2d23d0
Sep 29 16:06:02 hostname-removed kernel: [ 363.476954] R13: 00007ffd8b2d2870 R14: 00007ffd8b2d2580 R15: 00007ffd8b2d22c0

[James Elliott (jimatltsnet)]

I should note that the same script appears fine in Ubuntu 20.04, where the FIM build version is up to 0.5.3-2build1