Hi!

Am Sa., 9. März 2024 um 04:09 Uhr schrieb Joey Hess <id@joeyh.name>:

I'm confident I saw this same problem today, with packagekit repeatedly updating and spinning a CPU for 10 minutes. It only stopped at that
point because I stopped and masked it. (Stopping it was not enough,
something was restarting the service every time I stopped it.) See
attached log.

I did not capture the trigger for that pkmon, but just before it started I had used window+s in gnome and typed in "paperwm", before remembering that doesn't find anything and pressing escape.

When I repeat that with pkmon open, I see it does trigger packagekit:

root@darkstar:/home/joey>pkmon
Transactions:
[none]
daemon connected=1
network status=online
Transactions:
1 /14317_cdeeebeb
/14317_cdeeebeb allow_cancel 1
/14317_cdeeebeb percentage -1
/14317_cdeeebeb role resolve
/14317_cdeeebeb sender /usr/bin/gnome-software
/14317_cdeeebeb status setup

Unfortunately there isn't much we can do - this is GNOME Software
polling PackageKit again and again, and other than adding more rate limitations, we can't fix that in PK and PK is behaving correctly.
The real solution would be to make the tool that is causing this stop
its behavior and not ask PackageKit to update the cache every second.

I reassigned it to GS, which already had a bug describing this issue exactly.

Cheers,
Matthias

--
I welcome VSRE emails. See http://vsre.info/

--- SoupGate-Win32 v1.05
* Origin: fsxNet Usenet Gateway (21:1/5)

Joey Hess wrote:

It may also be relevant somehow that the topmost update was a thinkpad
AMD firmware update which "requires restart".

I masked and stopped packagekit again and now in gnome-software, it
displays only the thinkpad amd firmware update, and it's no longer
alternating with the loading updates screen.
This makes me think that firmware update is not related to the problem.

The other updates gnome-software displays when packagekit is running are
debian package updates. My last upgrade was an apt-get safe-upgrade,
because dist-upgrade wants to remove several packages, including gnome.
(I'm tracking unstable, this is typical transient dependency issues I
suppose. Also I have bluez on hold at an older version due to #1060224)

So maybe gnome-software gets confused in this kind of situation and keeps retrying?

--
see shy jo

-----BEGIN PGP SIGNATURE-----

iQIzBAABCgAdFiEEKKUAw1IH6rcvbA8l2xLbD/BfjzgFAmXsAUUACgkQ2xLbD/Bf jzhTbA//SrC8O5zySqFL3uokMjDiP0cnQzUA4YXT56qbspdumSLA3HmaABpGTpJw bWT7O5/QnylBYzDc+iFPE6uotgXMN4VuF3M2uJJY9vZxi2OZXfjcx7GJ22CNsYbb fFqhP5+SXniX0UfwyLLLs7l9Cu7CGjmfBb+Ef0nd8EPFDgAjjMC6jsLWdFH3dT9B 9A3MLPsD2T/MLbXujN0OJ2bzVtxAHnlUBKXObV6p+eF+VFIMfJbui2DV6zOPP1PX aijAY2dr6MXeAuJccdRJF/wqvS/EUU+BFSRcX6oZZoR9MBodYbySEWblNXChunhi WoHg0V3uiXWa0472ia3gQHl6K9kcqgtNHaNs5tZikmteZCBInWZ2+w06f64ssVVA SwaXqnkc5i8+D468Hwja5usD/IaRAA+eiUMYD+jIHPnfRHjhmeJG8EIlnlApLhQL +4R9Z4ah5Q2q2kuGkQ0k/bkVVE/RTAeyCY+WW151eTD14uq/oelmtQB63wXP3EnO Rt2gbt6kcEs6LdZE5ObGCjUpncUwh/+/WbS1HdT1hEzUeRMy7/paMSXWitmWN9rX J4Rl1GdES4weGLh3VBFoeqwr3PDUL4LNCfZzly7A3mSmIvvtbrknKIoTjG0Ka+Ce NqkZl81RZs5yY1iZyN8yyvbNDpxrxsb8WhdIDy8SCB2eR8Ud4VY=
=F6CI
-----END PGP SIGNATURE-----

--- SoupGate-Win32 v1.05
* Origin: fsxNet Usenet Gateway (21:1/5)

Below is a pkmon while the problem is occurring.

Since it points at gnome-software causing the activity, I tried opening
that. I noticed that the updates tab had an indicator that there were
updates. Switching to it, I saw it continuously alternate between
"Loading updates" with a spinner and a list of updates. Each transition
back to "Loading updates" corresponds to more pkmon activity.

It may also be relevant somehow that the topmost update was a thinkpad
AMD firmware update which "requires restart".

Transactions:
[none]
daemon connected=1
network status=online
Transactions:
1 /15795_aacdccdd
/15795_aacdccdd allow_cancel 1
/15795_aacdccdd percentage -1
/15795_aacdccdd role get-updates
/15795_aacdccdd sender /usr/bin/gnome-software
/15795_aacdccdd status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:25.371: GTask 0x556f0677f540 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15795_aacdccdd
2 /15796_cadeddaa
/15796_cadeddaa allow_cancel 1
/15796_cadeddaa percentage -1
/15796_cadeddaa role get-updates
/15796_cadeddaa sender /usr/bin/gnome-software
/15796_cadeddaa status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:25.442: GTask 0x556f06783290 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15796_cadeddaa
Transactions:
[none]
Transactions:
1 /15797_cbbadaab
Transactions:
1 /15797_cbbadaab
2 /15798_ceeaaabb
/15797_cbbadaab allow_cancel 1
/15797_cbbadaab percentage -1
/15797_cbbadaab role get-details
/15797_cbbadaab sender /usr/bin/gnome-software
/15797_cbbadaab status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:27.100: GTask 0x556f06783390 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
/15798_ceeaaabb allow_cancel 1
/15798_ceeaaabb percentage -1
/15798_ceeaaabb role get-updates
/15798_ceeaaabb sender /usr/bin/gnome-software
/15798_ceeaaabb status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:27.100: GTask 0x556f06784f30 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15798_ceeaaabb
Transactions:
[none]
Transactions:
1 /15799_eebaebcb
/15799_eebaebcb allow_cancel 1
/15799_eebaebcb percentage -1
/15799_eebaebcb role get-updates
/15799_eebaebcb sender /usr/bin/gnome-software
/15799_eebaebcb status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:31.374: GTask 0x556f06788660 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15799_eebaebcb
2 /15800_caeadeca
/15800_caeadeca allow_cancel 1
/15800_caeadeca percentage -1
/15800_caeadeca role get-updates
/15800_caeadeca sender /usr/bin/gnome-software
/15800_caeadeca status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:31.448: GTask 0x556f06783f90 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15800_caeadeca
Transactions:
[none]
Transactions:
1 /15801_dceaeeeb
Transactions:
1 /15801_dceaeeeb
2 /15802_cbacedec
/15801_dceaeeeb allow_cancel 1
/15801_dceaeeeb percentage -1
/15801_dceaeeeb role get-details
/15801_dceaeeeb sender /usr/bin/gnome-software
/15801_dceaeeeb status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:33.169: GTask 0x556f0677ee00 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
/15802_cbacedec allow_cancel 1
/15802_cbacedec percentage -1
/15802_cbacedec role get-updates
/15802_cbacedec sender /usr/bin/gnome-software
/15802_cbacedec status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:33.170: GTask 0x556f06788750 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15802_cbacedec
Transactions:
[none]
Transactions:
1 /15803_ecbdcdcd
/15803_ecbdcdcd allow_cancel 1
/15803_ecbdcdcd percentage -1
/15803_ecbdcdcd role get-updates
/15803_ecbdcdcd sender /usr/bin/gnome-software
/15803_ecbdcdcd status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:37.375: GTask 0x556f0678ba90 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15803_ecbdcdcd
2 /15804_adcdbabb
/15804_adcdbabb allow_cancel 1
/15804_adcdbabb percentage -1
/15804_adcdbabb role get-updates
/15804_adcdbabb sender /usr/bin/gnome-software
/15804_adcdbabb status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:37.447: GTask 0x556f0678c190 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15804_adcdbabb
Transactions:
[none]
Transactions:
1 /15805_babdddcb
Transactions:
1 /15805_babdddcb
2 /15806_cedadbbe
/15805_babdddcb allow_cancel 1
/15805_babdddcb percentage -1
/15805_babdddcb role get-details
/15805_babdddcb sender /usr/bin/gnome-software
/15805_babdddcb status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:39.149: GTask 0x556f06788610 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
/15806_cedadbbe allow_cancel 1
/15806_cedadbbe percentage -1
/15806_cedadbbe role get-updates
/15806_cedadbbe sender /usr/bin/gnome-software
/15806_cedadbbe status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:39.149: GTask 0x556f0678ce50 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15806_cedadbbe
Transactions:
[none]
Transactions:
1 /15807_edcdccae
/15807_edcdccae allow_cancel 1
/15807_edcdccae percentage -1
/15807_edcdccae role get-updates
/15807_edcdccae sender /usr/bin/gnome-software
/15807_edcdccae status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:43.372: GTask 0x556f0678d170 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15807_edcdccae
2 /15808_eeddedbd
/15808_eeddedbd allow_cancel 1
/15808_eeddedbd percentage -1
/15808_eeddedbd role get-updates
/15808_eeddedbd sender /usr/bin/gnome-software
/15808_eeddedbd status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:43.445: GTask 0x556f0678fc10 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15808_eeddedbd
Transactions:
[none]
Transactions:
1 /15809_aeceddca
Transactions:
1 /15809_aeceddca
2 /15810_aeaccdaa
/15809_aeceddca allow_cancel 1
/15809_aeceddca percentage -1
/15809_aeceddca role get-details
/15809_aeceddca sender /usr/bin/gnome-software
/15809_aeceddca status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:45.134: GTask 0x556f06790390 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
/15810_aeaccdaa allow_cancel 1
/15810_aeaccdaa percentage -1
/15810_aeaccdaa role get-updates
/15810_aeaccdaa sender /usr/bin/gnome-software
/15810_aeaccdaa status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:45.134: GTask 0x556f06790eb0 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15810_aeaccdaa
Transactions:
[none]
Transactions:
1 /15811_ccaaabcc
/15811_ccaaabcc allow_cancel 1
/15811_ccaaabcc percentage -1
/15811_ccaaabcc role get-updates
/15811_ccaaabcc sender /usr/bin/gnome-software
/15811_ccaaabcc status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:49.373: GTask 0x556f06791d40 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15811_ccaaabcc
2 /15812_eededbba
/15812_eededbba allow_cancel 1
/15812_eededbba percentage -1
/15812_eededbba role get-updates
/15812_eededbba sender /usr/bin/gnome-software
/15812_eededbba status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:49.445: GTask 0x556f0677d2d0 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15812_eededbba
Transactions:
[none]
Transactions:
1 /15813_accbdcba
Transactions:
1 /15813_accbdcba
2 /15814_ceaebdaa
/15813_accbdcba allow_cancel 1
/15813_accbdcba percentage -1
/15813_accbdcba role get-details
/15813_accbdcba sender /usr/bin/gnome-software
/15813_accbdcba status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:51.162: GTask 0x556f06792620 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
/15814_ceaebdaa allow_cancel 1
/15814_ceaebdaa percentage -1
/15814_ceaebdaa role get-updates
/15814_ceaebdaa sender /usr/bin/gnome-software
/15814_ceaebdaa status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:51.163: GTask 0x556f06794d00 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15814_ceaebdaa
Transactions:
[none]
Transactions:
1 /15815_addecede
/15815_addecede allow_cancel 1
/15815_addecede percentage -1
/15815_addecede role get-updates
/15815_addecede sender /usr/bin/gnome-software
/15815_addecede status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:55.375: GTask 0x556f06782830 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15815_addecede
2 /15816_cbeadece
/15816_cbeadece allow_cancel 1
/15816_cbeadece percentage -1
/15816_cbeadece role get-updates
/15816_cbeadece sender /usr/bin/gnome-software
/15816_cbeadece status wait

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:55.445: GTask 0x556f06793c70 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
Transactions:
1 /15816_cbeadece
Transactions:
[none]
Transactions:
1 /15817_daedddcc
Transactions:
1 /15817_daedddcc
2 /15818_aabdaecb
/15817_daedddcc allow_cancel 1
/15817_daedddcc percentage -1
/15817_daedddcc role get-details
/15817_daedddcc sender /usr/bin/gnome-software
/15817_daedddcc status setup

(pkmon:3985472): GLib-GIO-CRITICAL **: 02:02:57.164: GTask 0x556f06797e80 (source object: 0x556f0677c360, source tag: 0x7f08eae881c0) finalized without ever returning (using g_task_return_*()). This potentially indicates a bug in the program.
/15818_aabdaecb allow_cancel 1
/15818_aabdaecb percentage -1
/15818_aabdaecb role get-updates
/15818_aabdaecb sender /usr/bin/gnome-software
/15818_aabdaecb status wait

--
see shy jo

-----BEGIN PGP SIGNATURE-----

iQIzBAABCgAdFiEEKKUAw1IH6rcvbA8l2xLbD/BfjzgFAmXr/qgACgkQ2xLbD/Bf jzjdGBAAiogMjhxSMX84FdBNFMDzjbONtulaGGht34esHlvi3eIg4mqxt9mNEpFG sYebLX4WALYTx0sZUAWMunii84Ac7qM8XAtTK+z+01dCl/uU4ijarNk83IrN3DcP Wu+aLdaBevpaCGL9zHUHkqc+BokPdxshsjJYgq7pRz5UdUtvn4nI4/bKed6iWXf3 bQctGICz0SJ4IeFNcCvPzlu5MfBa6UidiQoHGF9IyHgV2FINhXP8RBVUSlRo7maq oUf+m50Dx1ywk6r5zExs4GoviirS+nP/HGy/4+IC1pVU6YQDNswJM5P6dE6+cji+ smLrniqrM08t856tXjnYzKa2FGWZok4wcJF4mwVQ+NFZNqCspyGwqOuh7CRnawat 9xwMFKgJXjAdY5Xcq1TKY1qL4Gb6OFqRfkHxabpRUcX/atQmdmdyjJ5rnk4zmrab 2+9BUlLC/8x7EUU33VixeOXK22bo6beP/hcl0ksrkUGcLIbra3JTj4kS4bZ9PU1p sNPjQp7ZIxjDbfmecnQxK3xO1V3dWi+0HE89vUgkPJCUMyPRUJiNzD8Xu6RjCj4f 3K7wCGkEBi1gUszAJKl6/ULtpYGrYRFrphxTd5glYayq9MevVkFDk3QBmZQLnlhG iD7J7zKh+kO9nMgUrnoeJVlKqXqjSRgvNc6tP5Vh33r8s0MkXiw=
=Q7s4
-----END PGP SIGNATURE-----

--- SoupGate-Win32 v1.05
* Origin: fsxNet Usenet Gateway (21:1/5)

Am Sa., 9. März 2024 um 07:30 Uhr schrieb Joey Hess <id@joeyh.name>:

Joey Hess wrote:

It may also be relevant somehow that the topmost update was a thinkpad
AMD firmware update which "requires restart".

I masked and stopped packagekit again and now in gnome-software, it
displays only the thinkpad amd firmware update, and it's no longer alternating with the loading updates screen.
This makes me think that firmware update is not related to the problem.

The other updates gnome-software displays when packagekit is running are debian package updates. My last upgrade was an apt-get safe-upgrade,
because dist-upgrade wants to remove several packages, including gnome.
(I'm tracking unstable, this is typical transient dependency issues I suppose. Also I have bluez on hold at an older version due to #1060224)

So maybe gnome-software gets confused in this kind of situation and keeps retrying?

That is the current hypothesis, yes - the change that broke this was
introduced by Fedora, and they do not observe this behavior. So,
either GNOME Software is wrong (I think it unconditionally has a
problem, it should never retry a cache refresh at that insane
frequency), or the APT backend in PackageKit does something wrong and
emits package changes for blocked packages when it shouldn't do so.
I guess as soon as your system is up-to-date (with no blocked
packages), this issue will go away temporarily.

TBH, at this point I think there's probably a bug in GS as well as in
PK-Apt, but we haven't found the culprit yet (except for the GS patch
that caused this issue to appear, https://gitlab.gnome.org/GNOME/gnome-software/-/commit/b8cf52e9c001064eebfe86ce8555501541ca211e
)

Cheers,
Matthias

--
I welcome VSRE emails. See http://vsre.info/

--- SoupGate-Win32 v1.05
* Origin: fsxNet Usenet Gateway (21:1/5)

Matthias Klumpp wrote:

either GNOME Software is wrong (I think it unconditionally has a
problem, it should never retry a cache refresh at that insane
frequency), or the APT backend in PackageKit does something wrong and
emits package changes for blocked packages when it shouldn't do so.
I guess as soon as your system is up-to-date (with no blocked
packages), this issue will go away temporarily.

Is there anything I can do while I seem to be able to reproduce this
pretty easily?

--
see shy jo

-----BEGIN PGP SIGNATURE-----

iQIzBAABCgAdFiEEKKUAw1IH6rcvbA8l2xLbD/BfjzgFAmXsppAACgkQ2xLbD/Bf jzj57w//dC1nQ5bTwa1F9CWXKjUXw3uTEeR2yDkxoJ5USkBiopg4AeD1Y65bM+bW y59myUOqGEbEdVQ/TW8VCBPcwQl7sjE5Lt6clwRzL1lCAU2iNo9NLmw0M/A/UT1z VlI5NknsnwZlhhvRxZCgZAB5Xh6MWStevQ2hnSFA/SKflOv0tj6HzV2D41Wod7E+ 4pFc5WDyX9bwO/xeVuBZ5efW+XfPUXUkciJCLDboyPgmbRWm6OrEn/hkZ4Tlc/Te nsh+5Z36FjhLtnLp0X8pWxOKcZ9lyQFUzBXeQVC1b5c9q+OpkQ5VQ9iJ5dW8zXY4 eNSPaVlm29rOkIGKK/C/Km1oXua152Hc0f2+3t/tW4YNEBggIdiCQvXw92LRO5wu rm1b76GFW1KtsuVj8YaFevCEe6vd0egQFYeahuaFNOEyNvhoBgGIc+WWSaHxMTOk gnH/gGhUaSBj/U7+ZMmjgipxhnWTktweU0Vs1W1Uw82CE+yvMC0OMIsQd5g0Vuo8 GJQ2Xb74iBh0969cPoydn8WnhHiXJIsdE9gZ40b8Yp41VdzyYvKdLaN7e+IFlZW2 NBXdKrY4NY/MIGckh37XXsMMomLx6ykCjDBYLOdeGL9DVtvWc8f9iYbqvMGuYY2x 5SVxH5mOq0sCTBNrT+ejJPuvSLzDMm7PU75TsBVjA/cf1JY1AEE=
=YJUQ
-----END PGP SIGNATURE-----

--- SoupGate-Win32 v1.05
* Origin: fsxNet Usenet Gateway (21:1/5)