Upgrade woes - continued

[Peter <pmc@citylink.dinoex.sub.org>]

Beginning the second day. I found these in the console:

(emacs:23113): Gtk-WARNING **: 00:11:51.947: no trigger event for menu popup

(emacs:22750): dconf-WARNING **: 23:31:25.201: failed to commit changes to
dconf: Error spawning command line “dbus-launch
--autolaunch=41eb1750243211de992d00e0 1836f13c --binary-syntax
--close-stderr”: Child process exited with code 1

So I started focussing on Dbus. Anyway, this is not a warning, this is
a Bug: unauthorized use of Dbus (emacs is compiled *WITHOUT* dbus).
( BTW, I found that the same goes for firefox: compiled WITHOUT dbus,
but nevertheless happily relies on it if it finds it working. Bug
report sent, probably they won't care about it either. We now finally
achieved to have in our Unix the security features as of Windows
3.0. )

The error message itself has the simple reason that dbus happened to
be compiled without X11 support (which I actually think is a good idea,
because if it cannot run, it cannot do much harm).

The other warning (no trigger event ...) is still unexplained.

But, the emacs stalls seemed to be independent of that, so it led
me to nothing.

So, after searching through all kinds of configurations and trying
lots of compile options, I focused on the fact that only one of my two
displays experienced the problem. Such an issue should normally be
solveable via comparative analysis.

I had a look into truss, and there the difference was obvious. I
start the application, then wait 60 seconds (to let things settle),
then click on a menu. And it goes like this:

For the well-behaving instance:

14916 100931: 60.100816644 0.437710521 pselect(0x6,0x8211e0ca0,0x8211e0c20,0x0,0x8211e0e90,0x0) = 1 (0x1)
14916 100931: 60.101339239 0.438233116 SIGNAL 23 (SIGIO) code=SI_KERNEL
14916 100931: 60.101500771 0.000049619 sigprocmask(SIG_SETMASK,{ SIGINT|SIGQUIT|SIGALRM|SIGCHLD|SIGIO|SIGPROF|SIGWINCH },0x0) = 0 (0x0)
14916 100931: 60.101608627 0.000015179 sigreturn(0x8211df580) EJUSTRETURN
14916 100931: 60.101708207 0.000010597 sigprocmask(SIG_BLOCK,{ SIGINT },{ }) = 0 (0x0)
14916 100931: 60.101748843 0.000009586 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0)
14916 100931: 60.101791862 0.000011067 ktimer_settime(0x3,0x1,0x8211e0c20,0x0) = 0 (0x0)
14916 100931: 60.101829886 0.000009982 setitimer(ITIMER_REAL,{ 0.000000, 0.000000 },{ 0.000000, 0.000000 }) = 0 (0x0)
14916 100931: 60.101869750 0.000009975 ktimer_settime(0x3,0x1,0x8211e0be0,0x0) = 0 (0x0)
14916 100931: 60.101928483 0.000015763 recvmsg(4,{NULL,0,[{"#\M^CH\^U\^V\0\0\0\^D\0\^B\0\^A`"...,4096}],1,{},0,0},0) = 120 (0x78)
14916 100931: 60.102117144 0.000009055 recvmsg(4,0x8211e0850,0) ERR#35 'Resource temporarily unavailable'
14916 100931: 60.102166875 0.000012190 poll({ 4/POLLIN 5/POLLIN },2,0) = 0 (0x0)
14916 100931: 60.102205338 0.000009139 poll({ 4/POLLIN 5/POLLIN },2,0) = 0 (0x0)
14916 100931: 60.103176619 0.000010532 poll({ 4/POLLIN|POLLOUT },1,-1) = 1 (0x1)
14916 100931: 60.103374962 0.000027609 writev(4,[{"\^R\0\a\0\^_\0 \^A\M-_\^A\0\0\^F"...,60},{0x0,0},{0x82ea936f6,0}],3) = 60 (0x3c)
14916 100931: 60.104093492 0.000746139 SIGNAL 23 (SIGIO) code=SI_KERNEL

After few ms a writev() is sent to the X, and then things will proceed
and create the pulldown menu.

But on the misbehaving machine:

16504 103406: 59.863694510 1.463688805 pselect(0x6,0x820a8d718,0x820a8d698,0x0,0x820a8dbc0,0x0) = 1 (0x1)
16504 103406: 59.863838309 1.463832604 SIGNAL 23 (SIGIO) code=SI_KERNEL
16504 103406: 59.864080464 0.000093898 sigprocmask(SIG_SETMASK,{ SIGINT|SIGQUIT|SIGALRM|SIGCHLD|SIGIO|SIGPROF|SIGWINCH },0x0) = 0 (0x0)
16504 103406: 59.864351417 0.000103501 sigreturn(0x820a8c5c0) EJUSTRETURN
16504 103406: 59.864621112 0.000102583 sigprocmask(SIG_BLOCK,{ SIGINT },{ }) = 0 (0x0)
16504 103406: 59.864890626 0.000085195 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0)
16504 103406: 59.865110165 0.000075782 ktimer_settime(0x3,0x1,0x820a8d678,0x0) = 0 (0x0)
16504 103406: 59.865272324 0.000044930 setitimer(ITIMER_REAL,{ 0.000000, 0.000000 },{ 0.000000, 0.000000 }) = 0 (0x0)
16504 103406: 59.865403126 0.000032022 ktimer_settime(0x3,0x1,0x820a8d638,0x0) = 0 (0x0)
16504 103406: 59.865498172 0.000024859 recvmsg(4,{NULL,0,[{"#\M^C\M-B\^Q\^V\0\0\0\^D\0\^B\0"...,4096}],1,{},0,0},0) = 120 (0x78)
16504 103406: 59.865786008 0.000019925 recvmsg(4,0x820a8d240,0) ERR#35 'Resource temporarily unavailable'
16504 103406: 59.865882720 0.000022628 poll({ 4/POLLIN 5/POLLIN },2,0) = 0 (0x0)
16504 103406: 59.865971134 0.000020027 poll({ 4/POLLIN 5/POLLIN },2,0) = 0 (0x0)
16504 103406: 59.869017870 0.000032982 openat(AT_FDCWD,"/home/pmc/.emacs.d/auto-save-list",O_RDONLY|O_DIRECTORY|O_CLOEXEC|O_PATH,00) = 6 (0x6)
16504 103406: 59.869095732 0.000021318 close(6)  = 0 (0x0)
16504 103406: 59.869291711 0.000024434 openat(AT_FDCWD,"/home/pmc/.emacs.d/auto-save-list",O_RDONLY|O_DIRECTORY|O_CLOEXEC,00) = 6 (0x6)
16504 103406: 59.869369989 0.000019758 fcntl(6,F_SETFD,FD_CLOEXEC) = 0 (0x0)
16504 103406: 59.869447747 0.000019840 fcntl(6,F_ISUNIONSTACK,0x0) = 0 (0x0)
16504 103406: 59.869583366 0.000073494 getdirentries(6,"\M^?$\^B\0\0\0\0\0\^A\0\0\0\0\0"...,4096,{ 0x0 }) = 64 (0x40)
16504 103406: 59.869849702 0.000042224 getdirentries(6,0x868893000,4096,{ 0x2 }) = 0 (0x0)
16504 103406: 59.869965757 0.000049032 close(6)  = 0 (0x0)
16504 103406: 59.896186766 0.000053565 faccessat(AT_FDCWD,"/usr/local/share/emacs/29.1/etc/HELLO",R_OK,AT_EACCESS) = 0 (0x0)
16504 103406: 60.013462550 0.000054448 madvise(0x87945b000,4096,MADV_FREE) = 0 (0x0)

  <lots of MADV_FREE following ...>

16504 103406: 60.015686812 0.000021107 madvise(0x8688fd000,12288,MADV_FREE) = 0 (0x0)
16504 103406: 60.047538257 0.031872552 SIGNAL 23 (SIGIO) code=SI_KERNEL
16504 103406: 60.047635908 0.000021275 sigprocmask(SIG_SETMASK,{ SIGINT|SIGQUIT|SIGALRM|SIGCHLD|SIGIO|SIGPROF|SIGWINCH },0x0) = 0 (0x0)
16504 103406: 60.047783371 0.000046972 sigreturn(0x820a89740) EJUSTRETURN
16504 103406: 60.047894148 0.000019976 sigprocmask(SIG_BLOCK,{ SIGINT|SIGALRM },{ }) = 0 (0x0)
16504 103406: 60.048036350 0.000046744 ktimer_settime(0x3,0x1,0x820a8a0b8,0x0) = 0 (0x0)
16504 103406: 60.048146351 0.000020089 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0)
16504 103406: 60.048259206 0.000022281 sigprocmask(SIG_BLOCK,{ SIGINT|SIGALRM },{ }) = 0 (0x0)
16504 103406: 60.048353156 0.000019982 ktimer_settime(0x3,0x1,0x820a8a0b8,0x0) = 0 (0x0)
16504 103406: 60.048438208 0.000019942 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0)
16504 103406: 60.048532516 0.000019892 sigprocmask(SIG_BLOCK,{ SIGINT|SIGALRM },{ }) = 0 (0x0)
16504 103406: 60.048624540 0.000019807 ktimer_settime(0x3,0x1,0x820a8a0b8,0x0) = 0 (0x0)
16504 103406: 60.048702615 0.000015082 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0)

  <... and we are in an endless loop consuming full CPU >

16504 103406: 91.322627562 0.000010078 ktimer_settime(0x3,0x1,0x820a7dac8,0x0) = 0 (0x0)
16504 103406: 91.322666990 0.000007095 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0)
16504 103406: 91.323026327 0.000007976 sigprocmask(SIG_BLOCK,{ SIGINT|SIGALRM },{ }) = 0 (0x0)
16504 103406: 91.323074420 0.000010756 ktimer_settime(0x3,0x1,0x820a7e638,0x0) = 0 (0x0)
16504 103406: 91.323116920 0.000007831 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0)
16504 103406: 91.326100104 0.000015713 madvise(0x879355000,16384,MADV_FREE) = 0 (0x0)

  <lots of MADV_FREE again>

16504 103406: 91.326985881 0.000011733 madvise(0x8796ca000,61440,MADV_FREE) = 0 (0x0)
16504 103406: 91.327109245 0.000010199 poll({ 4/POLLIN|POLLOUT },1,-1) = 1 (0x1)
16504 103406: 91.327217862 0.000047352 writev(4,[{"$(\^A\0\M^C(\^C\0\M-e\^C\0\0\^B"...,16},{0x0,0},{0x82cb091e6,0}],3) = 16 (0x10)
16504 103406: 91.327252355 0.000081845 SIGNAL 23 (SIGIO) code=SI_KERNEL

And here finally is the writev() sent and the menu unfolds -
31 seconds later. (Running with truss seems to slow things down.)

I don't know why it wants to read the auto-save-list, even less what
it might intend to do with that HELLO file...

Now with evening and morning, about this was the second day.