Bug 782932

Summary: Slow shutdown with systemd-37-7
Product: [Fedora] Fedora Reporter: John Watzke <watzkej>
Component: systemdAssignee: systemd-maint
Status: CLOSED INSUFFICIENT_DATA QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: medium Docs Contact:
Priority: unspecified    
Version: 17CC: jantho, johannbg, metherid, mschmidt, notting, plautrba, systemd-maint
Target Milestone: ---   
Target Release: ---   
Hardware: i686   
OS: Linux   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2013-06-15 15:41:36 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Attachments:
Description Flags
dmesg output using systemd-shutdown debug script
none
systemd-37-8 debug log with debug kernel params on
none
systemd-37-3 debug log with debug kernel params on none

Description John Watzke 2012-01-18 21:25:52 UTC
Created attachment 556121 [details]
dmesg output using systemd-shutdown debug script

Description of problem:
System takes a long time to shutdown with systemd-37-7.fc16.i686 installed.

Version-Release number of selected component (if applicable):
systemd-37-7.fc16.i686

How reproducible:
Always


Steps to Reproduce:
1. Shutdown the system
  
Actual results:
Slow shutdown (couple of minutes)

Expected results:
Shutdown should be relatively fast

Additional info:
This doesn't happen when I downgrade to systemd-37-6.fc16.i686
Attached is the dmesg output using systemd-shutdown debug script.

Comment 1 Michal Schmidt 2012-01-19 10:14:05 UTC
How long does "systemctl stop oracle-xe.service" usually take?
Does the system shutdown quickly if you stop oracle-xe beforehand?

Could you capture the shutdown log once more, but this time after having booted with "log_buf_len=1M systemd.log_level=debug systemd.log_target=kmsg"?
Also getting the same log from the working systemd version may help us spot the difference.

Comment 2 John Watzke 2012-01-20 04:27:24 UTC
So I noticed that it wasn't a downgrade to 37-6 but to what's current in stable, 37-3, where the problem doesn't occur.

To answer your questions:

1) [root@jwatzke ~]# time systemctl stop oracle-xe.service

real	0m7.367s
user	0m0.002s
sys	0m0.003s

2) If I shutdown oracle-xe before, it does shutdown faster.

3) I'm attaching debug-systemd-37-8.txt (slightly newer version but still exhibits issue) which has the shutdown log with the debug kernel params turned on.

4) I'm attaching debug-systemd-37-3.txt which has the shutdown log with the debug kernel params turned on for systemd-37-3.  I can see in that log systemd is able to correctly shutdown oracle-xe quickly.

Comment 3 John Watzke 2012-01-20 04:28:11 UTC
Created attachment 556444 [details]
systemd-37-8 debug log with debug kernel params on

Comment 4 John Watzke 2012-01-20 04:29:11 UTC
Created attachment 556445 [details]
systemd-37-3 debug log with debug kernel params on

Comment 5 Michal Schmidt 2012-06-07 15:22:46 UTC
I remember looking at the logs, but I did not figure out anything.
Are you still seeing the bug?
It could help to setup a debug shell and poke around a bit while the service is stopping. To see what processes are running, perhaps get some backtraces...
http://fedoraproject.org/wiki/Systemd_early_debug_shell

Comment 6 John Watzke 2012-06-08 02:29:48 UTC
Yes this still happens in F17 so I've updated the bug to reflect that.  Let me see if I can setup the debug shell and get some more info.

Comment 7 Jóhann B. Guðmundsson 2013-06-15 15:41:36 UTC
This bug report has been in needinfo status way to long thus closing. 

Feel free to reopen and providing the requested information.

Thanks