{"action":"created","issue":{"url":"https://api.github.com/repos/systemd/systemd/issues/1505","labels_url":"https://api.github.com/repos/systemd/systemd/issues/1505/labels{/name}","comments_url":"https://api.github.com/repos/systemd/systemd/issues/1505/comments","events_url":"https://api.github.com/repos/systemd/systemd/issues/1505/events","html_url":"https://github.com/systemd/systemd/issues/1505","id":110599823,"number":1505,"title":"[227 regression] Boot occasionally fails with \"Connection timed out\"","user":{"login":"martinpitt","id":200109,"avatar_url":"https://avatars.githubusercontent.com/u/200109?v=3","gravatar_id":"","url":"https://api.github.com/users/martinpitt","html_url":"https://github.com/martinpitt","followers_url":"https://api.github.com/users/martinpitt/followers","following_url":"https://api.github.com/users/martinpitt/following{/other_user}","gists_url":"https://api.github.com/users/martinpitt/gists{/gist_id}","starred_url":"https://api.github.com/users/martinpitt/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/martinpitt/subscriptions","organizations_url":"https://api.github.com/users/martinpitt/orgs","repos_url":"https://api.github.com/users/martinpitt/repos","events_url":"https://api.github.com/users/martinpitt/events{/privacy}","received_events_url":"https://api.github.com/users/martinpitt/received_events","type":"User","site_admin":false},"labels":[{"url":"https://api.github.com/repos/systemd/systemd/labels/regression","name":"regression","color":"e11d21"},{"url":"https://api.github.com/repos/systemd/systemd/labels/release-critical","name":"release-critical","color":"e11d21"}],"state":"open","locked":false,"assignee":null,"milestone":{"url":"https://api.github.com/repos/systemd/systemd/milestones/5","html_url":"https://github.com/systemd/systemd/milestones/v228","labels_url":"https://api.github.com/repos/systemd/systemd/milestones/5/labels","id":1294140,"number":5,"title":"v228","description":"","creator":{"login":"poettering","id":2130732,"avatar_url":"https://avatars.githubusercontent.com/u/2130732?v=3","gravatar_id":"","url":"https://api.github.com/users/poettering","html_url":"https://github.com/poettering","followers_url":"https://api.github.com/users/poettering/followers","following_url":"https://api.github.com/users/poettering/following{/other_user}","gists_url":"https://api.github.com/users/poettering/gists{/gist_id}","starred_url":"https://api.github.com/users/poettering/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/poettering/subscriptions","organizations_url":"https://api.github.com/users/poettering/orgs","repos_url":"https://api.github.com/users/poettering/repos","events_url":"https://api.github.com/users/poettering/events{/privacy}","received_events_url":"https://api.github.com/users/poettering/received_events","type":"User","site_admin":false},"open_issues":3,"closed_issues":9,"state":"open","created_at":"2015-09-07T17:01:06Z","updated_at":"2015-10-12T13:30:54Z","due_on":null,"closed_at":null},"comments":84,"created_at":"2015-10-09T07:07:49Z","updated_at":"2015-10-27T09:34:42Z","closed_at":null,"body":"https://bugs.debian.org/801354 reported that 227 introduced a rather major boot regression: You often get failures like\r\n\r\n```\r\nOct 09 09:00:19 autopkgtest systemd-logind[616]: Failed to enable subscription: Connection timed out\r\nOct 09 09:00:19 autopkgtest systemd-logind[616]: Failed to fully start up daemon: Connection timed out\r\nOct 09 09:00:19 autopkgtest systemd[1]: Failed to subscribe to activation signal: Connection timed out\r\nOct 09 09:00:44 autopkgtest systemd[1]: Failed to register name: Connection timed out\r\nOct 09 09:00:44 autopkgtest systemd[1]: Failed to set up API bus: Connection timed out\r\nOct 09 09:01:56 autopkgtest su[764]: pam_systemd(su:session): Failed to create session: Connection timed out\r\nOct 09 09:01:58 autopkgtest lightdm[1578]: pam_systemd(lightdm-greeter:session): Failed to create session: Connection timed out\r\n```\r\n(same for lightdm, etc.) I now found a way to reproduce this in a VM, so I'll go bisect hunting.\r\n\r\nAs for the pam_systemd thing: I wonder if that's related to the regression that the user systemd instance is now trying to unmount things during boot:\r\n\r\n```\r\nsystemd[1653]: Reached target Default.\r\nsystemd[1653]: boot.mount: Mount process exited, code=exited status=1\r\nsystemd[1653]: Failed unmounting /boot.\r\nsystemd[1653]: run-user-1000.mount: Mount process exited, code=exited status=1\r\nsystemd[1653]: Failed unmounting /run/user/1000.\r\nsystemd[1653]: dev-dm\\x2d2.swap: Unit entered failed state.\r\nsystemd[1653]: sys-kernel-debug.mount: Mount process exited, code=exited status=1\r\nsystemd[1653]: Failed unmounting /sys/kernel/debug.\r\n[...]\r\numount[1657]: umount: /boot: umount failed: Operation not permitted\r\numount[1658]: umount: /run/user/1000: umount failed: Operation not permitted\r\nswapoff[1659]: swapoff: Not superuser.\r\n```\r\n\r\nThis might be something entirely different (and then mostly cosmetical), or be the cause for failing to create a user session."},"comment":{"url":"https://api.github.com/repos/systemd/systemd/issues/comments/151431884","html_url":"https://github.com/systemd/systemd/issues/1505#issuecomment-151431884","issue_url":"https://api.github.com/repos/systemd/systemd/issues/1505","id":151431884,"user":{"login":"cinquecento","id":10598241,"avatar_url":"https://avatars.githubusercontent.com/u/10598241?v=3","gravatar_id":"","url":"https://api.github.com/users/cinquecento","html_url":"https://github.com/cinquecento","followers_url":"https://api.github.com/users/cinquecento/followers","following_url":"https://api.github.com/users/cinquecento/following{/other_user}","gists_url":"https://api.github.com/users/cinquecento/gists{/gist_id}","starred_url":"https://api.github.com/users/cinquecento/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/cinquecento/subscriptions","organizations_url":"https://api.github.com/users/cinquecento/orgs","repos_url":"https://api.github.com/users/cinquecento/repos","events_url":"https://api.github.com/users/cinquecento/events{/privacy}","received_events_url":"https://api.github.com/users/cinquecento/received_events","type":"User","site_admin":false},"created_at":"2015-10-27T09:34:42Z","updated_at":"2015-10-27T09:34:42Z","body":"On baremetal\r\n```\r\njournalctl:\r\n[    2.018551] lnx systemd[1]: systemd 227 running in system mode. ...\r\n...\r\n[   24.318682] lnx systemd[1]: systemd 227 running in system mode. ...\r\n...\r\n[   27.886607] lnx systemd[1]: Starting Login Service...\r\n...\r\n[   56.993810] lnx systemd[1]: systemd-logind.service: Failed with result 'exit-code'.\r\n[   57.062757] lnx systemd[1]: systemd-logind.service: Service has no hold-off time, scheduling restart.\r\n[   57.617978] lnx systemd[1]: Starting System Logging Service...\r\n[   57.660510] lnx systemd-logind[803]: Failed to enable subscription: Connection timed out\r\n[   57.743995] lnx systemd[1]: Failed to register name: Connection timed out\r\n[   57.745051] lnx systemd-logind[803]: Failed to fully start up daemon: Connection timed out\r\n[   57.764790] lnx systemd[1]: Failed to set up API bus: Connection timed out\r\n[   57.767440] lnx systemd[1]: Started System Logging Service.\r\n[   57.768577] lnx systemd[1]: systemd-logind.service: Main process exited, code=exited, status=1/FAILURE\r\n[   58.161986] lnx systemd[1]: Failed to start Login Service.\r\n[   58.163169] lnx systemd[1]: systemd-logind.service: Unit entered failed state.\r\n[   58.165909] lnx systemd-logind[943]: New seat seat0.\r\n[   59.121087] lnx systemd-logind[943]: Watching system buttons on /dev/input/event1 (Power Button)\r\n[   59.122276] lnx systemd-logind[943]: Watching system buttons on /dev/input/event0 (Power Button)\r\n\r\n[   64.077522] lnx systemd[1]: Reached target Login Prompts.\r\n...\r\n[   70.271739] lnx systemd[1]: Reached target Multi-User System.\r\n[   70.273408] lnx systemd[1]: Starting Update UTMP about System Runlevel Changes...\r\n[   70.311879] lnx systemd[1]: Started Update UTMP about System Runlevel Changes.\r\n...\r\n[   77.891727] lnx systemd[1]: Created slice user-0.slice.\r\n[   77.892945] lnx systemd[1]: Starting User Manager for UID 0...\r\n[   77.893433] lnx systemd-logind[943]: New session 1 of user root.\r\n[   77.893883] lnx systemd[1]: Started Session 1 of user root.\r\n[   77.934784] lnx systemd[1530]: pam_unix(systemd-user:session): session opened for user root by (uid=0)\r\n[   78.069342] lnx systemd[1530]: Reached target Timers.\r\n[   78.069954] lnx systemd[1530]: Reached target Paths.\r\n[   78.070673] lnx systemd[1530]: Listening on D-Bus User Message Bus Socket.\r\n[   78.071191] lnx systemd[1530]: Reached target Sockets.\r\n[   78.071672] lnx systemd[1530]: Reached target Basic System.\r\n[   78.072165] lnx systemd[1530]: Reached target Default.\r\n[   78.072652] lnx systemd[1530]: Startup finished in 131ms.\r\n[   78.073145] lnx systemd[1]: Started User Manager for UID 0.\r\n...\r\n[  102.871352] lnx login[1203]: pam_systemd(login:session): Failed to create session: Connection timed out\r\n[  102.905656] lnx login[1203]: pam_unix(login:session): session opened for user root by LOGIN(uid=0)\r\n[  102.906459] lnx login[1203]: ROOT LOGIN ON tty1\r\n...\r\n[  155.423445] lnx systemd[1]: wpa_supplicant.service: Start operation timed out. Terminating.\r\n[  155.424097] lnx systemd[1]: polkit.service: Start operation timed out. Terminating.\r\n[  155.426350] lnx systemd[1]: Failed to start WPA Supplicant daemon.\r\n[  155.427015] lnx systemd[1]: wpa_supplicant.service: Unit entered failed state.\r\n[  155.428418] lnx systemd[1]: wpa_supplicant.service: Failed with result 'timeout'.\r\n[  155.429116] lnx systemd[1]: Failed to start Authorization Manager.\r\n[  155.429578] lnx systemd[1]: polkit.service: Unit entered failed state.\r\n[  155.430044] lnx systemd[1]: polkit.service: Failed with result 'timeout'.\r\n[  157.173438] lnx systemd[1]: colord.service: Start operation timed out. Terminating.\r\n[  157.183121] lnx systemd[1]: Failed to start Manage, Install and Generate Color Profiles.\r\n[  157.183714] lnx systemd[1]: Startup finished in 1.902s (kernel) + 6.484s (initrd) + 2min 28.795s (userspace) = 2min 37.182s.\r\n[  157.184192] lnx systemd[1]: colord.service: Unit entered failed state.\r\n[  157.184316] lnx systemd[1]: colord.service: Failed with result 'timeout'.\r\n```\r\nsystemd-227-1.fc24.x86_64\r\n"}}