I have a new problem I haven't seen until recently on Fedora 26 (I don't recall running into this during development, so I'm guessing it's only shown up in the last month). This is on Fedora 26 Server but should apply equally to Workstation:
So what you'll see below is the timer triggers pretty much once an hour, and I guess figures out whether it's stale or not and if it is it downloads new metadata. But then at a certain point it just hangs and stops updating for hours and hours, and then I can't run dnf because it's already running. Continues after the journal output... [chris@f26s ~]$ sudo journalctl -b | grep dnf [sudo] password for chris: Jul 17 18:23:38 f26s.localdomain systemd[1]: Started dnf makecache timer. Jul 17 18:33:20 f26s.localdomain systemd[1]: Starting dnf makecache... Jul 17 18:33:29 f26s.localdomain dnf[1358]: Last metadata expiration check: 0:41:45 ago on Mon 17 Jul 2017 05:51:42 PM MDT. Jul 17 18:33:29 f26s.localdomain dnf[1358]: Metadata cache created. Jul 17 18:33:29 f26s.localdomain systemd[1]: Started dnf makecache. Jul 17 18:33:29 f26s.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 18:33:29 f26s.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 19:34:10 f26s.localdomain systemd[1]: Starting dnf makecache... Jul 17 19:34:11 f26s.localdomain dnf[1750]: Metadata cache refreshed recently. Jul 17 19:34:11 f26s.localdomain systemd[1]: Started dnf makecache. Jul 17 19:34:11 f26s.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 19:34:11 f26s.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 20:34:20 f26s.localdomain systemd[1]: Starting dnf makecache... Jul 17 20:34:21 f26s.localdomain dnf[2021]: Metadata cache refreshed recently. Jul 17 20:34:21 f26s.localdomain systemd[1]: Started dnf makecache. Jul 17 20:34:21 f26s.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 20:34:21 f26s.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 21:35:20 f26s.localdomain systemd[1]: Starting dnf makecache... Jul 17 21:36:28 f26s.localdomain dnf[2337]: Last metadata expiration check: 0:00:00 ago on Mon 17 Jul 2017 09:36:27 PM MDT. Jul 17 21:36:31 f26s.localdomain dnf[2337]: Metadata cache created. Jul 17 21:36:31 f26s.localdomain systemd[1]: Started dnf makecache. Jul 17 21:36:31 f26s.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 21:36:31 f26s.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 22:37:10 f26s.localdomain systemd[1]: Starting dnf makecache... Jul 17 22:37:11 f26s.localdomain dnf[2621]: Metadata cache refreshed recently. Jul 17 22:37:11 f26s.localdomain systemd[1]: Started dnf makecache. Jul 17 22:37:11 f26s.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 22:37:11 f26s.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 23:37:20 f26s.localdomain systemd[1]: Starting dnf makecache... Jul 17 23:37:21 f26s.localdomain dnf[2894]: Metadata cache refreshed recently. Jul 17 23:37:21 f26s.localdomain systemd[1]: Started dnf makecache. Jul 17 23:37:21 f26s.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 17 23:37:21 f26s.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 18 00:38:20 f26s.localdomain systemd[1]: Starting dnf makecache... [chris@f26s ~]$ ps -aux | grep dnf root 3379 0.0 1.9 529028 78916 ? SNs 00:38 0:01 /usr/libexec/system-python /usr/bin/dnf makecache timer chris 5371 0.0 0.0 119448 968 pts/0 S+ 07:47 0:00 grep --color=auto dnf [chris@f26s ~]$ sudo dnf update Waiting for process with pid 3379 to finish. ^CKeyboardInterrupt: Terminated. [chris@f26s ~]$ I have no good ideas how to figure out why it's hanging: [chris@f26s ~]$ sudo strace -p 3379 strace: Process 3379 attached futex(0x55eabc09e110, FUTEX_WAIT_PRIVATE, 2, NULL Anyway it seems like a bug but figured I'd ask and see if anyone else is experiencing this. -- Chris Murphy _______________________________________________ devel mailing list -- devel@lists.fedoraproject.org To unsubscribe send an email to devel-le...@lists.fedoraproject.org
