{"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":85,"created_at":"2015-10-09T07:07:49Z","updated_at":"2015-10-27T09:37:22Z","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/151433092","html_url":"https://github.com/systemd/systemd/issues/1505#issuecomment-151433092","issue_url":"https://api.github.com/repos/systemd/systemd/issues/1505","id":151433092,"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:37:22Z","updated_at":"2015-10-27T09:37:22Z","body":"On plastic fantastic\r\n```\r\n# hostnamectl \r\n   Static hostname: lnx\r\n         Icon name: computer-vm\r\n           Chassis: vm\r\n        Machine ID: 3d2a55ed3b014c90accdbb4b0e494921\r\n           Boot ID: 9a5637be158e4acd9bc3b5bb4a262996\r\n    Virtualization: qemu\r\n  Operating System: Fedora 24 (Rawhide)\r\n       CPE OS Name: cpe:/o:fedoraproject:fedora:24\r\n            Kernel: Linux 4.3.0-0.rc7.git0.1.fc24.x86_64\r\n      Architecture: x86-64\r\n\r\n\r\n# grep -R systemd-vconsole-setup /usr/lib/dracut/modules.d/ ; echo $?\r\n1\r\n\r\n\r\n# journalctl --list-boots \r\n-30 29d9b73f6718466fbb723818e6260220 Tue 2015-10-27 04:55:40 EDT—Tue 2015-10-27 04:56:05 EDT\r\n-29 b47a406c43674f84a41902a96852c362 Tue 2015-10-27 04:56:10 EDT—Tue 2015-10-27 04:56:26 EDT\r\n-28 47ec03d952c44542be07c49db99f2960 Tue 2015-10-27 04:56:31 EDT—Tue 2015-10-27 04:56:45 EDT\r\n-27 614d436df8d3442ea838fe24deb9c0d7 Tue 2015-10-27 04:56:50 EDT—Tue 2015-10-27 04:57:03 EDT\r\n-26 d352c87d997a41ab85235617baee6d43 Tue 2015-10-27 04:57:09 EDT—Tue 2015-10-27 04:57:22 EDT\r\n-25 8ba7c744e1d0499f9307ab62dff01cc1 Tue 2015-10-27 04:57:27 EDT—Tue 2015-10-27 04:57:42 EDT\r\n-24 6da525abd2934d3a9b8af337dc4b1762 Tue 2015-10-27 04:57:47 EDT—Tue 2015-10-27 04:58:00 EDT\r\n-23 fa43d20e96644997914178f6b7fe8691 Tue 2015-10-27 04:58:07 EDT—Tue 2015-10-27 04:58:19 EDT\r\n-22 fbe3c8256bbe4f8a991040aba2bf2752 Tue 2015-10-27 04:58:25 EDT—Tue 2015-10-27 04:58:38 EDT\r\n-21 36e709a6f0d948fb87670b01cf9df7a8 Tue 2015-10-27 04:58:43 EDT—Tue 2015-10-27 04:58:57 EDT\r\n-20 f793670a749c4972a2316e866d2e5e8e Tue 2015-10-27 04:59:04 EDT—Tue 2015-10-27 04:59:17 EDT\r\n-19 2b94390983424981a1bf1f65c7965e51 Tue 2015-10-27 04:59:23 EDT—Tue 2015-10-27 04:59:36 EDT\r\n-18 47f5927bf30345079b05d79482c42247 Tue 2015-10-27 04:59:42 EDT—Tue 2015-10-27 05:00:08 EDT\r\n-17 c15245bc24ec45cd978a84e0bbf2823d Tue 2015-10-27 05:00:13 EDT—Tue 2015-10-27 05:00:25 EDT\r\n-16 9dbae9de32814060b2e46f0629d827e1 Tue 2015-10-27 05:00:31 EDT—Tue 2015-10-27 05:00:44 EDT\r\n-15 bf9a7c227c364d80b5f7abd3343f72ab Tue 2015-10-27 05:00:49 EDT—Tue 2015-10-27 05:01:01 EDT\r\n-14 449a7ae3be304993acb1e2efcea18467 Tue 2015-10-27 05:01:07 EDT—Tue 2015-10-27 05:01:27 EDT\r\n-13 10f7679933334c33b2f060a5c2a298b2 Tue 2015-10-27 05:01:32 EDT—Tue 2015-10-27 05:01:45 EDT\r\n-12 d39c31e93fb549559a1c0ec47a96836c Tue 2015-10-27 05:01:51 EDT—Tue 2015-10-27 05:02:08 EDT\r\n-11 6316372eb0f540b688ab2c1bfb359167 Tue 2015-10-27 05:02:13 EDT—Tue 2015-10-27 05:02:26 EDT\r\n-10 7b81a527e825433c94a077c41e82a405 Tue 2015-10-27 05:02:32 EDT—Tue 2015-10-27 05:02:46 EDT\r\n -9 31b4cc77cafc40168e3b3ba5e6aec9bd Tue 2015-10-27 05:02:51 EDT—Tue 2015-10-27 05:03:13 EDT\r\n -8 522dfaed82db4beb85ba662a18378362 Tue 2015-10-27 05:03:18 EDT—Tue 2015-10-27 05:03:32 EDT\r\n -7 f961d863098b423cb77bbf59a2b119b4 Tue 2015-10-27 05:03:38 EDT—Tue 2015-10-27 05:03:52 EDT\r\n -6 fa06d11db4444d03a0aefefeb33a0f72 Tue 2015-10-27 05:03:57 EDT—Tue 2015-10-27 05:04:14 EDT\r\n -5 d3e1c4850dce43868192eac435dde5b9 Tue 2015-10-27 05:04:19 EDT—Tue 2015-10-27 05:04:36 EDT\r\n -4 51206ccf6218418c9564257754a84817 Tue 2015-10-27 05:04:41 EDT—Tue 2015-10-27 05:05:02 EDT\r\n -3 4bd31e130c3f47b99d48e13b942ade97 Tue 2015-10-27 05:05:08 EDT—Tue 2015-10-27 05:05:22 EDT\r\n -2 7a70cd517574477daddddf5c1a166062 Tue 2015-10-27 05:05:28 EDT—Tue 2015-10-27 05:05:44 EDT\r\n -1 cc60d9793ce14c4aa5d93eb2a306b1be Tue 2015-10-27 05:05:49 EDT—Tue 2015-10-27 05:06:13 EDT\r\n  0 9a5637be158e4acd9bc3b5bb4a262996 Tue 2015-10-27 05:06:18 EDT—Tue 2015-10-27 05:12:20 EDT\r\n\r\n\r\n# journalctl -o short-iso | grep -P -i '(?=.*Login)(?=.*Failed)'\r\n2015-10-27T04:56:04-0400 lnx systemd-logind[810]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T04:56:25-0400 lnx systemd-logind[815]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T04:56:44-0400 lnx systemd-logind[810]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T04:57:02-0400 lnx systemd-logind[805]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T04:57:21-0400 lnx lightdm[1110]: pam_systemd(lightdm-autologin:session): Failed to release session: Connection reset by peer\r\n2015-10-27T04:57:21-0400 lnx systemd-logind[814]: Failed to abandon session scope: Transport endpoint is not connected\r\n2015-10-27T04:57:41-0400 lnx systemd-logind[796]: Failed to abandon session scope: Transport endpoint is not connected\r\n2015-10-27T04:57:59-0400 lnx systemd-logind[804]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T04:58:18-0400 lnx systemd-logind[810]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T04:58:37-0400 lnx systemd-logind[817]: Failed to abandon session scope: Transport endpoint is not connected\r\n2015-10-27T04:59:16-0400 lnx systemd-logind[824]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T04:59:35-0400 lnx systemd-logind[816]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:00:07-0400 lnx systemd-logind[796]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:00:42-0400 lnx systemd-logind[812]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:01:00-0400 lnx systemd-logind[800]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:02:05-0400 lnx systemd-logind[828]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:02:24-0400 lnx systemd-logind[806]: Failed to abandon session scope: Transport endpoint is not connected\r\n2015-10-27T05:02:44-0400 lnx systemd-logind[809]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:03:11-0400 lnx systemd-logind[812]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:03:11-0400 lnx systemd-logind[812]: Failed to abandon session scope: Transport endpoint is not connected\r\n2015-10-27T05:03:30-0400 lnx systemd-logind[793]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:03:51-0400 lnx systemd-logind[805]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:04:32-0400 lnx systemd-logind[810]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:05:00-0400 lnx systemd-logind[808]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:05:00-0400 lnx systemd-logind[808]: Failed to abandon session scope: Transport endpoint is not connected\r\n2015-10-27T05:05:39-0400 lnx systemd-logind[802]: Failed to abandon session scope: Transport endpoint is not connected\r\n2015-10-27T05:06:11-0400 lnx systemd-logind[792]: Failed to abandon session scope: Connection reset by peer\r\n2015-10-27T05:06:11-0400 lnx systemd-logind[792]: Failed to abandon session scope: Transport endpoint is not connected\r\n```\r\nTest compilation:\r\nhttp://goo.gl/Gm4ffO\r\niso/Rawhide-Xfce-Live-1026.iso\r\n\r\nTested w/ installed system - 'liveinst --extlinux'\r\n"}}